{"level":"warn","ts":"2026-04-17T06:07:32.723019Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T06:07:32.723093Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-04-17T06:07:32.723148Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T06:07:32.723159Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-04-17T06:07:32.723176Z","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-04-17T06:07:32.723735Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-04-17T06:07:32.723802Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-04-17T06:07:32.728058Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.080555ms"} {"level":"info","ts":"2026-04-17T06:07:32.733647Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-04-17T06:07:32.73371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T06:07:32.733732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-04-17T06:07:32.733753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T06:07:32.733769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-04-17T06:07:32.733803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-04-17T06:07:32.738852Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T06:07:32.740152Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T06:07:32.741238Z","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-04-17T06:07:32.743182Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T06:07:32.743373Z","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-04-17T06:07:32.743426Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T06:07:32.743508Z","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-04-17T06:07:32.743527Z","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-04-17T06:07:32.747079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-04-17T06:07:32.747246Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-04-17T06:07:32.748995Z","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-04-17T06:07:32.749116Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-04-17T06:07:32.749143Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-04-17T06:07:32.750091Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T06:07:32.750124Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T06:07:33.634595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T06:07:33.634657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T06:07:33.634681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-04-17T06:07:33.634694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-04-17T06:07:33.634701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-04-17T06:07:33.634713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-04-17T06:07:33.634722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-04-17T06:07:33.635708Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T06:07:33.63576Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T06:07:33.635932Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T06:07:33.635981Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T06:07:33.635876Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T06:07:33.635929Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T06:07:33.637105Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T06:07:33.637297Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T06:07:33.63736Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T06:07:33.639392Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T06:07:33.639743Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-04-17T06:07:53.476984Z","caller":"traceutil/trace.go:171","msg":"trace[862367522] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"103.552898ms","start":"2026-04-17T06:07:53.373408Z","end":"2026-04-17T06:07:53.476961Z","steps":["trace[862367522] 'process raft request' (duration: 103.423036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:07:53.926428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.832229ms","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-04-17T06:07:53.926516Z","caller":"traceutil/trace.go:171","msg":"trace[599785369] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:415; }","duration":"107.93831ms","start":"2026-04-17T06:07:53.818559Z","end":"2026-04-17T06:07:53.926498Z","steps":["trace[599785369] 'range keys from in-memory index tree' (duration: 107.745118ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:07:54.28769Z","caller":"traceutil/trace.go:171","msg":"trace[418286599] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"107.264564ms","start":"2026-04-17T06:07:54.180405Z","end":"2026-04-17T06:07:54.28767Z","steps":["trace[418286599] 'process raft request' (duration: 107.122972ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:07:59.856972Z","caller":"traceutil/trace.go:171","msg":"trace[1023167312] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:476; }","duration":"303.366843ms","start":"2026-04-17T06:07:59.553581Z","end":"2026-04-17T06:07:59.856948Z","steps":["trace[1023167312] 'read index received' (duration: 302.950808ms)","trace[1023167312] 'applied index is now lower than readState.Index' (duration: 414.725µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:07:59.857037Z","caller":"traceutil/trace.go:171","msg":"trace[936659173] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"351.724229ms","start":"2026-04-17T06:07:59.505284Z","end":"2026-04-17T06:07:59.857008Z","steps":["trace[936659173] 'process raft request' (duration: 351.530576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:07:59.85722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.571886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:07:59.857375Z","caller":"traceutil/trace.go:171","msg":"trace[1821572942] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:464; }","duration":"303.763028ms","start":"2026-04-17T06:07:59.553545Z","end":"2026-04-17T06:07:59.857308Z","steps":["trace[1821572942] 'agreement among raft nodes before linearized reading' (duration: 303.504185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:07:59.857525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:07:59.553532Z","time spent":"303.970992ms","remote":"127.0.0.1:49512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-17T06:07:59.857598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:07:59.50527Z","time spent":"351.83562ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:08:06.126298Z","caller":"traceutil/trace.go:171","msg":"trace[863496596] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"223.467911ms","start":"2026-04-17T06:08:05.902793Z","end":"2026-04-17T06:08:06.126261Z","steps":["trace[863496596] 'process raft request' (duration: 222.60891ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:08:06.42635Z","caller":"traceutil/trace.go:171","msg":"trace[1521402670] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"460.492308ms","start":"2026-04-17T06:08:05.965818Z","end":"2026-04-17T06:08:06.42631Z","steps":["trace[1521402670] 'process raft request' (duration: 460.200014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:08:06.426923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:08:05.965792Z","time spent":"460.66322ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:08:06.427551Z","caller":"traceutil/trace.go:171","msg":"trace[579057537] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:585; }","duration":"302.682013ms","start":"2026-04-17T06:08:06.124813Z","end":"2026-04-17T06:08:06.427495Z","steps":["trace[579057537] 'read index received' (duration: 678.019µs)","trace[579057537] 'applied index is now lower than readState.Index' (duration: 302.002754ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:08:06.42782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.825009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-17T06:08:06.427854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.040107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-17T06:08:06.427881Z","caller":"traceutil/trace.go:171","msg":"trace[989888656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:570; }","duration":"250.92711ms","start":"2026-04-17T06:08:06.176938Z","end":"2026-04-17T06:08:06.427865Z","steps":["trace[989888656] 'agreement among raft nodes before linearized reading' (duration: 250.762758ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:08:06.427919Z","caller":"traceutil/trace.go:171","msg":"trace[1437057520] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"303.133118ms","start":"2026-04-17T06:08:06.124763Z","end":"2026-04-17T06:08:06.427896Z","steps":["trace[1437057520] 'agreement among raft nodes before linearized reading' (duration: 302.818704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:08:06.428102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:08:06.124743Z","time spent":"303.309741ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T06:08:06.427551Z","caller":"traceutil/trace.go:171","msg":"trace[208367079] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"324.827553ms","start":"2026-04-17T06:08:06.102701Z","end":"2026-04-17T06:08:06.427529Z","steps":["trace[208367079] 'process raft request' (duration: 323.680227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:08:06.428881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:08:06.102672Z","time spent":"326.12096ms","remote":"127.0.0.1:49512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:08:08.204702Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.258898769s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T06:08:08.207119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.563618709s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:08:08.207336Z","caller":"traceutil/trace.go:171","msg":"trace[1913885191] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"1.63731382s","start":"2026-04-17T06:08:06.569996Z","end":"2026-04-17T06:08:08.20731Z","steps":["trace[1913885191] 'process raft request' (duration: 73.294456ms)","trace[1913885191] 'compare' (duration: 1.563436257s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:08:08.207451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:08:06.569972Z","time spent":"1.637422911s","remote":"127.0.0.1:49614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:08:08.207804Z","caller":"traceutil/trace.go:171","msg":"trace[692032100] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"1.263544743s","start":"2026-04-17T06:08:06.944249Z","end":"2026-04-17T06:08:08.207794Z","steps":["trace[692032100] 'process raft request' (duration: 1.263084936s)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:08:08.207811Z","caller":"traceutil/trace.go:171","msg":"trace[673081058] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"1.567329589s","start":"2026-04-17T06:08:06.640461Z","end":"2026-04-17T06:08:08.20779Z","steps":["trace[673081058] 'read index received' (duration: 2.741288ms)","trace[673081058] 'applied index is now lower than readState.Index' (duration: 1.564585601s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:08:08.207858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:08:06.944218Z","time spent":"1.263613953s","remote":"127.0.0.1:49512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:08:08.208006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.080491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:08:08.208081Z","caller":"traceutil/trace.go:171","msg":"trace[282964615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"725.201973ms","start":"2026-04-17T06:08:07.48286Z","end":"2026-04-17T06:08:08.208062Z","steps":["trace[282964615] 'agreement among raft nodes before linearized reading' (duration: 725.065871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:08:08.208122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:08:07.482839Z","time spent":"725.274884ms","remote":"127.0.0.1:49420","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-04-17T06:08:08.208088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.567578723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-17T06:08:08.208208Z","caller":"traceutil/trace.go:171","msg":"trace[830534361] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:576; }","duration":"1.567769575s","start":"2026-04-17T06:08:06.640411Z","end":"2026-04-17T06:08:08.20818Z","steps":["trace[830534361] 'agreement among raft nodes before linearized reading' (duration: 1.567481751s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:08:08.208284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:08:06.64039Z","time spent":"1.567879767s","remote":"127.0.0.1:49448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.229\" "} {"level":"info","ts":"2026-04-17T06:08:09.980703Z","caller":"traceutil/trace.go:171","msg":"trace[1832118599] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"346.99923ms","start":"2026-04-17T06:08:09.633679Z","end":"2026-04-17T06:08:09.980678Z","steps":["trace[1832118599] 'process raft request' (duration: 346.865198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:08:09.980841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:08:09.633653Z","time spent":"347.129052ms","remote":"127.0.0.1:49468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T06:08:09.983899Z","caller":"traceutil/trace.go:171","msg":"trace[1844658635] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"148.99952ms","start":"2026-04-17T06:08:09.834871Z","end":"2026-04-17T06:08:09.98387Z","steps":["trace[1844658635] 'process raft request' (duration: 148.884899ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:08:11.619551Z","caller":"traceutil/trace.go:171","msg":"trace[680182095] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"172.847373ms","start":"2026-04-17T06:08:11.446678Z","end":"2026-04-17T06:08:11.619525Z","steps":["trace[680182095] 'process raft request' (duration: 172.546159ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:08:11.660198Z","caller":"traceutil/trace.go:171","msg":"trace[249387538] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"121.729423ms","start":"2026-04-17T06:08:11.538425Z","end":"2026-04-17T06:08:11.660154Z","steps":["trace[249387538] 'process raft request' (duration: 121.469629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:08:12.283836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.27764ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-17T06:08:12.284253Z","caller":"traceutil/trace.go:171","msg":"trace[1927679671] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:661; }","duration":"251.915864ms","start":"2026-04-17T06:08:12.032319Z","end":"2026-04-17T06:08:12.284235Z","steps":["trace[1927679671] 'read index received' (duration: 138.985115ms)","trace[1927679671] 'applied index is now lower than readState.Index' (duration: 112.929819ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:08:12.284408Z","caller":"traceutil/trace.go:171","msg":"trace[500568226] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"446.370335ms","start":"2026-04-17T06:08:11.837977Z","end":"2026-04-17T06:08:12.284347Z","steps":["trace[500568226] 'process raft request' (duration: 446.124401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:08:12.284573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:08:11.83793Z","time spent":"446.565598ms","remote":"127.0.0.1:49468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T06:08:12.284815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.466452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-04-17T06:08:12.284926Z","caller":"traceutil/trace.go:171","msg":"trace[1206891112] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:643; }","duration":"252.640944ms","start":"2026-04-17T06:08:12.032264Z","end":"2026-04-17T06:08:12.284905Z","steps":["trace[1206891112] 'agreement among raft nodes before linearized reading' (duration: 252.139877ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:08:12.414155Z","caller":"traceutil/trace.go:171","msg":"trace[458229006] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"117.795614ms","start":"2026-04-17T06:08:12.296336Z","end":"2026-04-17T06:08:12.414132Z","steps":["trace[458229006] 'process raft request' (duration: 108.002974ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:08:24.700874Z","caller":"traceutil/trace.go:171","msg":"trace[1783662303] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"113.381805ms","start":"2026-04-17T06:08:24.587447Z","end":"2026-04-17T06:08:24.700829Z","steps":["trace[1783662303] 'process raft request' (duration: 112.92926ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:08:26.418642Z","caller":"traceutil/trace.go:171","msg":"trace[881610788] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"697.773205ms","start":"2026-04-17T06:08:25.720815Z","end":"2026-04-17T06:08:26.418588Z","steps":["trace[881610788] 'process raft request' (duration: 697.493031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:08:26.419212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:08:25.720773Z","time spent":"698.23562ms","remote":"127.0.0.1:49676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:08:26.61175Z","caller":"traceutil/trace.go:171","msg":"trace[1580743565] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:768; }","duration":"215.879433ms","start":"2026-04-17T06:08:26.395848Z","end":"2026-04-17T06:08:26.611727Z","steps":["trace[1580743565] 'read index received' (duration: 22.451454ms)","trace[1580743565] 'applied index is now lower than readState.Index' (duration: 193.426789ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:08:26.612084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.224317ms","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-04-17T06:08:26.612158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.852346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:08:26.612172Z","caller":"traceutil/trace.go:171","msg":"trace[1919202278] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:747; }","duration":"216.350059ms","start":"2026-04-17T06:08:26.395794Z","end":"2026-04-17T06:08:26.612144Z","steps":["trace[1919202278] 'agreement among raft nodes before linearized reading' (duration: 216.144297ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:08:26.612216Z","caller":"traceutil/trace.go:171","msg":"trace[1253475964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"126.946127ms","start":"2026-04-17T06:08:26.485244Z","end":"2026-04-17T06:08:26.61219Z","steps":["trace[1253475964] 'agreement among raft nodes before linearized reading' (duration: 126.867196ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:08:26.612325Z","caller":"traceutil/trace.go:171","msg":"trace[981725942] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"888.469179ms","start":"2026-04-17T06:08:25.72381Z","end":"2026-04-17T06:08:26.612279Z","steps":["trace[981725942] 'process raft request' (duration: 880.384396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:08:26.612589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:08:25.723797Z","time spent":"888.654361ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:08:50.708251Z","caller":"traceutil/trace.go:171","msg":"trace[1297653912] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"116.490223ms","start":"2026-04-17T06:08:50.591745Z","end":"2026-04-17T06:08:50.708235Z","steps":["trace[1297653912] 'process raft request' (duration: 116.390312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:08:51.304399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.028796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:08:51.304549Z","caller":"traceutil/trace.go:171","msg":"trace[1996336726] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"566.549418ms","start":"2026-04-17T06:08:50.737973Z","end":"2026-04-17T06:08:51.304523Z","steps":["trace[1996336726] 'process raft request' (duration: 335.291589ms)","trace[1996336726] 'compare' (duration: 230.403358ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:08:51.304619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:08:50.737952Z","time spent":"566.636929ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:08:51.305341Z","caller":"traceutil/trace.go:171","msg":"trace[1760481918] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:958; }","duration":"437.320213ms","start":"2026-04-17T06:08:50.867984Z","end":"2026-04-17T06:08:51.305304Z","steps":["trace[1760481918] 'read index received' (duration: 205.312025ms)","trace[1760481918] 'applied index is now lower than readState.Index' (duration: 232.006718ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:08:51.305699Z","caller":"traceutil/trace.go:171","msg":"trace[1822731960] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"567.201735ms","start":"2026-04-17T06:08:50.738484Z","end":"2026-04-17T06:08:51.305686Z","steps":["trace[1822731960] 'process raft request' (duration: 566.688049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:08:51.305875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:08:50.73846Z","time spent":"567.284675ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:08:51.306168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.175065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-17T06:08:51.306167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.4915ms","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-04-17T06:08:51.306232Z","caller":"traceutil/trace.go:171","msg":"trace[1295518049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:930; }","duration":"428.269166ms","start":"2026-04-17T06:08:50.877948Z","end":"2026-04-17T06:08:51.306218Z","steps":["trace[1295518049] 'agreement among raft nodes before linearized reading' (duration: 428.097304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:08:51.306266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:08:50.877936Z","time spent":"428.324416ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T06:08:51.306275Z","caller":"traceutil/trace.go:171","msg":"trace[760932557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:930; }","duration":"434.659011ms","start":"2026-04-17T06:08:50.871588Z","end":"2026-04-17T06:08:51.306247Z","steps":["trace[760932557] 'agreement among raft nodes before linearized reading' (duration: 434.328877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:08:51.30635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:08:50.871574Z","time spent":"434.763643ms","remote":"127.0.0.1:42962","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-04-17T06:08:51.306153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.146633ms","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-04-17T06:08:51.306555Z","caller":"traceutil/trace.go:171","msg":"trace[867428799] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:930; }","duration":"438.594158ms","start":"2026-04-17T06:08:50.867937Z","end":"2026-04-17T06:08:51.306531Z","steps":["trace[867428799] 'agreement among raft nodes before linearized reading' (duration: 438.064162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:08:51.306628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:08:50.867922Z","time spent":"438.689259ms","remote":"127.0.0.1:42962","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-04-17T06:08:59.539702Z","caller":"traceutil/trace.go:171","msg":"trace[62119341] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"132.28397ms","start":"2026-04-17T06:08:59.407372Z","end":"2026-04-17T06:08:59.539656Z","steps":["trace[62119341] 'process raft request' (duration: 100.915975ms)","trace[62119341] 'compare' (duration: 31.117402ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:08:59.540618Z","caller":"traceutil/trace.go:171","msg":"trace[1586742903] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"133.02716ms","start":"2026-04-17T06:08:59.407572Z","end":"2026-04-17T06:08:59.540599Z","steps":["trace[1586742903] 'process raft request' (duration: 132.024308ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:08:59.540634Z","caller":"traceutil/trace.go:171","msg":"trace[1840331368] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"132.950569ms","start":"2026-04-17T06:08:59.407643Z","end":"2026-04-17T06:08:59.540594Z","steps":["trace[1840331368] 'process raft request' (duration: 132.764377ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:09:21.608356Z","caller":"traceutil/trace.go:171","msg":"trace[66776896] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"112.314186ms","start":"2026-04-17T06:09:21.496003Z","end":"2026-04-17T06:09:21.608317Z","steps":["trace[66776896] 'process raft request' (duration: 112.1388ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:09:26.994006Z","caller":"traceutil/trace.go:171","msg":"trace[745578145] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"152.699284ms","start":"2026-04-17T06:09:26.841286Z","end":"2026-04-17T06:09:26.993985Z","steps":["trace[745578145] 'process raft request' (duration: 152.554599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:28.169416Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880868788882605540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T06:09:28.669911Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880868788882605540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T06:09:29.170374Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880868788882605540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T06:09:29.671089Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880868788882605540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T06:09:29.78806Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.153220164s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T06:09:29.788364Z","caller":"traceutil/trace.go:171","msg":"trace[1497105091] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"2.153590947s","start":"2026-04-17T06:09:27.634754Z","end":"2026-04-17T06:09:29.788345Z","steps":["trace[1497105091] 'process raft request' (duration: 2.153456782s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:29.788489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:27.634738Z","time spent":"2.153685541s","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:09:30.484434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999894467s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-17T06:09:30.484522Z","caller":"traceutil/trace.go:171","msg":"trace[894450878] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000029532s","start":"2026-04-17T06:09:28.484474Z","end":"2026-04-17T06:09:30.484504Z","steps":["trace[894450878] 'agreement among raft nodes before linearized reading' (duration: 1.999918588s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:30.484576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:28.484433Z","time spent":"2.000133365s","remote":"127.0.0.1:49420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/17 06:09:30 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-17T06:09:31.05067Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.262436102s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T06:09:31.051596Z","caller":"traceutil/trace.go:171","msg":"trace[1051474785] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1089; }","duration":"3.38258652s","start":"2026-04-17T06:09:27.668988Z","end":"2026-04-17T06:09:31.051575Z","steps":["trace[1051474785] 'read index received' (duration: 2.119233644s)","trace[1051474785] 'applied index is now lower than readState.Index' (duration: 1.263351876s)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:09:31.051866Z","caller":"traceutil/trace.go:171","msg":"trace[886251642] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"3.41465073s","start":"2026-04-17T06:09:27.637204Z","end":"2026-04-17T06:09:31.051855Z","steps":["trace[886251642] 'process raft request' (duration: 3.413724617s)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:09:31.051907Z","caller":"traceutil/trace.go:171","msg":"trace[160190392] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"2.052753932s","start":"2026-04-17T06:09:28.99912Z","end":"2026-04-17T06:09:31.051874Z","steps":["trace[160190392] 'process raft request' (duration: 2.052418721s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:31.051979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:27.63719Z","time spent":"3.414718153s","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:09:31.052051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:28.999097Z","time spent":"2.052858596s","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:09:31.052238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.383255734s","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-04-17T06:09:31.052284Z","caller":"traceutil/trace.go:171","msg":"trace[1665051537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1054; }","duration":"3.383340987s","start":"2026-04-17T06:09:27.668932Z","end":"2026-04-17T06:09:31.052273Z","steps":["trace[1665051537] 'agreement among raft nodes before linearized reading' (duration: 3.383215022s)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:09:31.052288Z","caller":"traceutil/trace.go:171","msg":"trace[494407424] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"2.738060887s","start":"2026-04-17T06:09:28.314214Z","end":"2026-04-17T06:09:31.052275Z","steps":["trace[494407424] 'process raft request' (duration: 2.737279069s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:31.052316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:27.668911Z","time spent":"3.383399139s","remote":"127.0.0.1:42962","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-04-17T06:09:31.052348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:28.314197Z","time spent":"2.738121419s","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:09:31.09326Z","caller":"traceutil/trace.go:171","msg":"trace[2108640395] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"665.93028ms","start":"2026-04-17T06:09:30.427311Z","end":"2026-04-17T06:09:31.093241Z","steps":["trace[2108640395] 'process raft request' (duration: 665.738944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:31.093299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.189854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-17T06:09:31.093324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.418246798s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-17T06:09:31.093361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:30.427288Z","time spent":"666.019264ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:09:31.093373Z","caller":"traceutil/trace.go:171","msg":"trace[1364887451] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1055; }","duration":"836.319968ms","start":"2026-04-17T06:09:30.257035Z","end":"2026-04-17T06:09:31.093355Z","steps":["trace[1364887451] 'agreement among raft nodes before linearized reading' (duration: 836.163253ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:09:31.093393Z","caller":"traceutil/trace.go:171","msg":"trace[1940886912] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1055; }","duration":"3.418350682s","start":"2026-04-17T06:09:27.675025Z","end":"2026-04-17T06:09:31.093376Z","steps":["trace[1940886912] 'agreement among raft nodes before linearized reading' (duration: 3.418158675s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:31.093438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:27.675006Z","time spent":"3.418422665s","remote":"127.0.0.1:42962","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-04-17T06:09:31.093494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.943815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:09:31.093534Z","caller":"traceutil/trace.go:171","msg":"trace[1087938965] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1055; }","duration":"434.019768ms","start":"2026-04-17T06:09:30.659505Z","end":"2026-04-17T06:09:31.093525Z","steps":["trace[1087938965] 'agreement among raft nodes before linearized reading' (duration: 433.956335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:31.093569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:30.659487Z","time spent":"434.07397ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-17T06:09:31.093579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.863604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:09:31.093607Z","caller":"traceutil/trace.go:171","msg":"trace[395800590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1055; }","duration":"608.939617ms","start":"2026-04-17T06:09:30.48466Z","end":"2026-04-17T06:09:31.0936Z","steps":["trace[395800590] 'agreement among raft nodes before linearized reading' (duration: 608.899586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:31.093633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:30.484643Z","time spent":"608.985729ms","remote":"127.0.0.1:49406","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-04-17T06:09:31.093726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.151639586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T06:09:31.093764Z","caller":"traceutil/trace.go:171","msg":"trace[365242573] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1055; }","duration":"2.15172312s","start":"2026-04-17T06:09:28.942031Z","end":"2026-04-17T06:09:31.093755Z","steps":["trace[365242573] 'agreement among raft nodes before linearized reading' (duration: 2.151661627s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:31.093793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:28.941998Z","time spent":"2.151785562s","remote":"127.0.0.1:43186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-04-17T06:09:31.093824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203045873s","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-04-17T06:09:31.093856Z","caller":"traceutil/trace.go:171","msg":"trace[413960702] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1055; }","duration":"1.203110816s","start":"2026-04-17T06:09:29.890737Z","end":"2026-04-17T06:09:31.093848Z","steps":["trace[413960702] 'agreement among raft nodes before linearized reading' (duration: 1.203061834s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:31.093881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:29.890722Z","time spent":"1.203153347s","remote":"127.0.0.1:41968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-17T06:09:31.093436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:30.257012Z","time spent":"836.405242ms","remote":"127.0.0.1:43110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-17T06:09:31.093975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.414966631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T06:09:31.094005Z","caller":"traceutil/trace.go:171","msg":"trace[328161258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1055; }","duration":"3.415022103s","start":"2026-04-17T06:09:27.678975Z","end":"2026-04-17T06:09:31.093997Z","steps":["trace[328161258] 'agreement among raft nodes before linearized reading' (duration: 3.41492112s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:31.094032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:27.678962Z","time spent":"3.415064744s","remote":"127.0.0.1:42962","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-04-17T06:09:31.094175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.469023447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T06:09:31.094227Z","caller":"traceutil/trace.go:171","msg":"trace[1022342188] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1055; }","duration":"1.46910633s","start":"2026-04-17T06:09:29.625112Z","end":"2026-04-17T06:09:31.094218Z","steps":["trace[1022342188] 'agreement among raft nodes before linearized reading' (duration: 1.469033808s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:31.094247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:29.625095Z","time spent":"1.469147082s","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-04-17T06:09:50.530235Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880868788882605715,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T06:09:50.599861Z","caller":"traceutil/trace.go:171","msg":"trace[1509238645] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1160; }","duration":"570.472897ms","start":"2026-04-17T06:09:50.029365Z","end":"2026-04-17T06:09:50.599838Z","steps":["trace[1509238645] 'read index received' (duration: 570.210857ms)","trace[1509238645] 'applied index is now lower than readState.Index' (duration: 261.12µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:09:50.599984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.600453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:09:50.600017Z","caller":"traceutil/trace.go:171","msg":"trace[1978345581] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1118; }","duration":"570.692756ms","start":"2026-04-17T06:09:50.029315Z","end":"2026-04-17T06:09:50.600008Z","steps":["trace[1978345581] 'agreement among raft nodes before linearized reading' (duration: 570.619473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:50.600065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:50.029297Z","time spent":"570.753728ms","remote":"127.0.0.1:49568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-04-17T06:09:50.600067Z","caller":"traceutil/trace.go:171","msg":"trace[1719637706] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"674.042038ms","start":"2026-04-17T06:09:49.925847Z","end":"2026-04-17T06:09:50.599889Z","steps":["trace[1719637706] 'process raft request' (duration: 673.846871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:50.60029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:49.925832Z","time spent":"674.354151ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:09:51.281184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.236677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:09:51.28127Z","caller":"traceutil/trace.go:171","msg":"trace[437803605] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1118; }","duration":"439.382963ms","start":"2026-04-17T06:09:50.841868Z","end":"2026-04-17T06:09:51.28125Z","steps":["trace[437803605] 'count revisions from in-memory index tree' (duration: 439.138673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:51.281396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.093344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:09:51.281719Z","caller":"traceutil/trace.go:171","msg":"trace[296057395] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1118; }","duration":"406.435027ms","start":"2026-04-17T06:09:50.875253Z","end":"2026-04-17T06:09:51.281688Z","steps":["trace[296057395] 'count revisions from in-memory index tree' (duration: 405.997271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:51.281891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:50.875239Z","time spent":"406.632735ms","remote":"127.0.0.1:49660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-17T06:09:51.281591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:50.841845Z","time spent":"439.648953ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-04-17T06:09:51.681186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.7023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:09:51.681749Z","caller":"traceutil/trace.go:171","msg":"trace[190959721] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"321.694191ms","start":"2026-04-17T06:09:51.359822Z","end":"2026-04-17T06:09:51.681516Z","steps":["trace[190959721] 'process raft request' (duration: 69.582635ms)","trace[190959721] 'compare' (duration: 251.590946ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:09:51.681858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:51.359798Z","time spent":"322.020254ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:09:51.738254Z","caller":"traceutil/trace.go:171","msg":"trace[1150388728] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"331.252194ms","start":"2026-04-17T06:09:51.406982Z","end":"2026-04-17T06:09:51.738235Z","steps":["trace[1150388728] 'process raft request' (duration: 331.15153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:51.7384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:51.406957Z","time spent":"331.364319ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:09:51.738555Z","caller":"traceutil/trace.go:171","msg":"trace[765910876] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1167; }","duration":"253.989009ms","start":"2026-04-17T06:09:51.484544Z","end":"2026-04-17T06:09:51.738533Z","steps":["trace[765910876] 'read index received' (duration: 253.977688ms)","trace[765910876] 'applied index is now lower than readState.Index' (duration: 9.251µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:09:51.738676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.130426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:09:51.73874Z","caller":"traceutil/trace.go:171","msg":"trace[995408029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1124; }","duration":"254.212358ms","start":"2026-04-17T06:09:51.484495Z","end":"2026-04-17T06:09:51.738707Z","steps":["trace[995408029] 'agreement among raft nodes before linearized reading' (duration: 254.109625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:51.941572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.180953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:09:51.941682Z","caller":"traceutil/trace.go:171","msg":"trace[897822116] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1167; }","duration":"201.939258ms","start":"2026-04-17T06:09:51.739726Z","end":"2026-04-17T06:09:51.941665Z","steps":["trace[897822116] 'read index received' (duration: 45.454113ms)","trace[897822116] 'applied index is now lower than readState.Index' (duration: 156.483755ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:09:51.941766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.035802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:09:51.941741Z","caller":"traceutil/trace.go:171","msg":"trace[834409761] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"380.640871ms","start":"2026-04-17T06:09:51.56106Z","end":"2026-04-17T06:09:51.941701Z","steps":["trace[834409761] 'process raft request' (duration: 224.245299ms)","trace[834409761] 'compare' (duration: 156.08265ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:09:51.941797Z","caller":"traceutil/trace.go:171","msg":"trace[2038051452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1125; }","duration":"202.088734ms","start":"2026-04-17T06:09:51.739693Z","end":"2026-04-17T06:09:51.941782Z","steps":["trace[2038051452] 'agreement among raft nodes before linearized reading' (duration: 202.041022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:51.942104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:51.561036Z","time spent":"380.971213ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:09:53.646587Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.033919718s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T06:09:53.647148Z","caller":"traceutil/trace.go:171","msg":"trace[1634771816] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"1.034558474s","start":"2026-04-17T06:09:52.612565Z","end":"2026-04-17T06:09:53.647124Z","steps":["trace[1634771816] 'process raft request' (duration: 1.034229072s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:53.64731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:52.612526Z","time spent":"1.034695429s","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:09:53.937277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.052388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:09:53.937417Z","caller":"traceutil/trace.go:171","msg":"trace[1862527071] linearizableReadLoop","detail":"{readStateIndex:1171; appliedIndex:1169; }","duration":"603.595829ms","start":"2026-04-17T06:09:53.333801Z","end":"2026-04-17T06:09:53.937397Z","steps":["trace[1862527071] 'read index received' (duration: 313.003402ms)","trace[1862527071] 'applied index is now lower than readState.Index' (duration: 290.590787ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:09:53.937455Z","caller":"traceutil/trace.go:171","msg":"trace[602275934] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"647.666049ms","start":"2026-04-17T06:09:53.289735Z","end":"2026-04-17T06:09:53.937401Z","steps":["trace[602275934] 'process raft request' (duration: 491.378592ms)","trace[602275934] 'compare' (duration: 155.880221ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:09:53.937598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:53.289711Z","time spent":"647.808375ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:09:53.937613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.801448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-17T06:09:53.937632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.037248ms","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-04-17T06:09:53.937664Z","caller":"traceutil/trace.go:171","msg":"trace[1725375613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1127; }","duration":"583.09975ms","start":"2026-04-17T06:09:53.354554Z","end":"2026-04-17T06:09:53.937654Z","steps":["trace[1725375613] 'agreement among raft nodes before linearized reading' (duration: 582.956814ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:09:53.937678Z","caller":"traceutil/trace.go:171","msg":"trace[381975650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1127; }","duration":"603.890071ms","start":"2026-04-17T06:09:53.333758Z","end":"2026-04-17T06:09:53.937648Z","steps":["trace[381975650] 'agreement among raft nodes before linearized reading' (duration: 603.728584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:53.937702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:53.35454Z","time spent":"583.153271ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T06:09:53.937714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:53.333729Z","time spent":"603.977555ms","remote":"127.0.0.1:42962","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-04-17T06:09:53.937866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.579856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-17T06:09:53.937892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.7035ms","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-04-17T06:09:53.937906Z","caller":"traceutil/trace.go:171","msg":"trace[617272840] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1127; }","duration":"251.656789ms","start":"2026-04-17T06:09:53.68624Z","end":"2026-04-17T06:09:53.937896Z","steps":["trace[617272840] 'agreement among raft nodes before linearized reading' (duration: 251.561796ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:09:53.937924Z","caller":"traceutil/trace.go:171","msg":"trace[836926772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1127; }","duration":"567.760812ms","start":"2026-04-17T06:09:53.370154Z","end":"2026-04-17T06:09:53.937914Z","steps":["trace[836926772] 'agreement among raft nodes before linearized reading' (duration: 567.669628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:53.937952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:53.370141Z","time spent":"567.804843ms","remote":"127.0.0.1:42962","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-04-17T06:09:53.938042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.178072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:09:53.938065Z","caller":"traceutil/trace.go:171","msg":"trace[1316281405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1127; }","duration":"453.226843ms","start":"2026-04-17T06:09:53.484831Z","end":"2026-04-17T06:09:53.938058Z","steps":["trace[1316281405] 'agreement among raft nodes before linearized reading' (duration: 453.176992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:53.938088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:53.484817Z","time spent":"453.266574ms","remote":"127.0.0.1:49420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T06:09:54.276073Z","caller":"traceutil/trace.go:171","msg":"trace[1396188650] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"332.811875ms","start":"2026-04-17T06:09:53.943236Z","end":"2026-04-17T06:09:54.276048Z","steps":["trace[1396188650] 'process raft request' (duration: 332.740823ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:09:54.276295Z","caller":"traceutil/trace.go:171","msg":"trace[476759771] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"332.345086ms","start":"2026-04-17T06:09:53.943898Z","end":"2026-04-17T06:09:54.276243Z","steps":["trace[476759771] 'process raft request' (duration: 332.116587ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:09:54.276132Z","caller":"traceutil/trace.go:171","msg":"trace[1611153814] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"332.957021ms","start":"2026-04-17T06:09:53.94313Z","end":"2026-04-17T06:09:54.276087Z","steps":["trace[1611153814] 'process raft request' (duration: 332.654429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:09:54.2765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:53.943107Z","time spent":"333.289174ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:09:54.276511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:53.943889Z","time spent":"332.492221ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:09:54.276493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:09:53.943231Z","time spent":"333.009783ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:09:55.8888Z","caller":"traceutil/trace.go:171","msg":"trace[1431809630] linearizableReadLoop","detail":"{readStateIndex:1176; appliedIndex:1175; }","duration":"114.57588ms","start":"2026-04-17T06:09:55.774201Z","end":"2026-04-17T06:09:55.888777Z","steps":["trace[1431809630] 'read index received' (duration: 114.341011ms)","trace[1431809630] 'applied index is now lower than readState.Index' (duration: 233.969µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:09:55.88895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.720036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T06:09:55.888985Z","caller":"traceutil/trace.go:171","msg":"trace[1666372343] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1132; }","duration":"114.81293ms","start":"2026-04-17T06:09:55.77416Z","end":"2026-04-17T06:09:55.888973Z","steps":["trace[1666372343] 'agreement among raft nodes before linearized reading' (duration: 114.729967ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:09:55.888956Z","caller":"traceutil/trace.go:171","msg":"trace[1374658768] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"233.039212ms","start":"2026-04-17T06:09:55.655836Z","end":"2026-04-17T06:09:55.888876Z","steps":["trace[1374658768] 'process raft request' (duration: 232.797873ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:10:22.52053Z","caller":"traceutil/trace.go:171","msg":"trace[431828771] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"100.110469ms","start":"2026-04-17T06:10:22.420394Z","end":"2026-04-17T06:10:22.520505Z","steps":["trace[431828771] 'process raft request' (duration: 99.663783ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:10:22.52053Z","caller":"traceutil/trace.go:171","msg":"trace[1061301924] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"100.800121ms","start":"2026-04-17T06:10:22.419666Z","end":"2026-04-17T06:10:22.520466Z","steps":["trace[1061301924] 'process raft request' (duration: 46.506103ms)","trace[1061301924] 'compare' (duration: 53.76396ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:10:32.39389Z","caller":"traceutil/trace.go:171","msg":"trace[237246189] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"192.895102ms","start":"2026-04-17T06:10:32.200971Z","end":"2026-04-17T06:10:32.393866Z","steps":["trace[237246189] 'read index received' (duration: 192.78197ms)","trace[237246189] 'applied index is now lower than readState.Index' (duration: 111.902µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:10:32.394005Z","caller":"traceutil/trace.go:171","msg":"trace[850005242] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"249.381243ms","start":"2026-04-17T06:10:32.144592Z","end":"2026-04-17T06:10:32.393974Z","steps":["trace[850005242] 'process raft request' (duration: 249.128649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:10:32.39411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.120275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-17T06:10:32.394143Z","caller":"traceutil/trace.go:171","msg":"trace[23644026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1252; }","duration":"193.219987ms","start":"2026-04-17T06:10:32.200915Z","end":"2026-04-17T06:10:32.394135Z","steps":["trace[23644026] 'agreement among raft nodes before linearized reading' (duration: 193.040084ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:10:33.196729Z","caller":"traceutil/trace.go:171","msg":"trace[726563284] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"175.831689ms","start":"2026-04-17T06:10:33.02088Z","end":"2026-04-17T06:10:33.196712Z","steps":["trace[726563284] 'read index received' (duration: 175.655156ms)","trace[726563284] 'applied index is now lower than readState.Index' (duration: 175.783µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:10:33.196919Z","caller":"traceutil/trace.go:171","msg":"trace[647930333] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"192.904682ms","start":"2026-04-17T06:10:33.004006Z","end":"2026-04-17T06:10:33.19691Z","steps":["trace[647930333] 'process raft request' (duration: 192.577318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:10:33.197124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.234304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T06:10:33.197154Z","caller":"traceutil/trace.go:171","msg":"trace[1587776691] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1257; }","duration":"176.318825ms","start":"2026-04-17T06:10:33.020828Z","end":"2026-04-17T06:10:33.197147Z","steps":["trace[1587776691] 'agreement among raft nodes before linearized reading' (duration: 176.244304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:10:33.197285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.297793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T06:10:33.197313Z","caller":"traceutil/trace.go:171","msg":"trace[460139489] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1257; }","duration":"155.378254ms","start":"2026-04-17T06:10:33.041927Z","end":"2026-04-17T06:10:33.197306Z","steps":["trace[460139489] 'agreement among raft nodes before linearized reading' (duration: 155.337584ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:12:23.062769Z","caller":"traceutil/trace.go:171","msg":"trace[1078719681] transaction","detail":"{read_only:false; response_revision:1615; number_of_response:1; }","duration":"107.8885ms","start":"2026-04-17T06:12:22.954854Z","end":"2026-04-17T06:12:23.062743Z","steps":["trace[1078719681] 'process raft request' (duration: 107.684025ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:13:11.839091Z","caller":"traceutil/trace.go:171","msg":"trace[127638646] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"223.528926ms","start":"2026-04-17T06:13:11.615534Z","end":"2026-04-17T06:13:11.839062Z","steps":["trace[127638646] 'process raft request' (duration: 223.209688ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:13:11.83909Z","caller":"traceutil/trace.go:171","msg":"trace[1973781496] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"223.224729ms","start":"2026-04-17T06:13:11.615817Z","end":"2026-04-17T06:13:11.839042Z","steps":["trace[1973781496] 'process raft request' (duration: 223.152597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:13:36.000117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.872065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T06:13:36.000229Z","caller":"traceutil/trace.go:171","msg":"trace[1113216599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1853; }","duration":"262.003957ms","start":"2026-04-17T06:13:35.738191Z","end":"2026-04-17T06:13:36.000195Z","steps":["trace[1113216599] 'range keys from in-memory index tree' (duration: 261.729141ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:14:11.501848Z","caller":"traceutil/trace.go:171","msg":"trace[1783111732] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"206.341418ms","start":"2026-04-17T06:14:11.295472Z","end":"2026-04-17T06:14:11.501813Z","steps":["trace[1783111732] 'process raft request' (duration: 206.155043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:14:12.374511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.646061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:14:12.374601Z","caller":"traceutil/trace.go:171","msg":"trace[652334190] linearizableReadLoop","detail":"{readStateIndex:2068; appliedIndex:2066; }","duration":"891.500987ms","start":"2026-04-17T06:14:11.483088Z","end":"2026-04-17T06:14:12.374589Z","steps":["trace[652334190] 'read index received' (duration: 18.521892ms)","trace[652334190] 'applied index is now lower than readState.Index' (duration: 872.978204ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:14:12.374651Z","caller":"traceutil/trace.go:171","msg":"trace[311896455] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"942.070072ms","start":"2026-04-17T06:14:11.432573Z","end":"2026-04-17T06:14:12.374643Z","steps":["trace[311896455] 'process raft request' (duration: 240.219196ms)","trace[311896455] 'compare' (duration: 701.474737ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:14:12.374687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:14:11.432549Z","time spent":"942.117322ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:14:12.374733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.62767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:14:12.374813Z","caller":"traceutil/trace.go:171","msg":"trace[1962129837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1973; }","duration":"891.738402ms","start":"2026-04-17T06:14:11.483054Z","end":"2026-04-17T06:14:12.374792Z","steps":["trace[1962129837] 'agreement among raft nodes before linearized reading' (duration: 891.62057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:14:12.374852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:14:11.483037Z","time spent":"891.808135ms","remote":"127.0.0.1:49420","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-04-17T06:14:12.874897Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880868788882607983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T06:14:13.375427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880868788882607983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T06:14:13.451321Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.736981121s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T06:14:13.458158Z","caller":"traceutil/trace.go:171","msg":"trace[494583969] linearizableReadLoop","detail":"{readStateIndex:2069; appliedIndex:2068; }","duration":"1.083521767s","start":"2026-04-17T06:14:12.374619Z","end":"2026-04-17T06:14:13.458141Z","steps":["trace[494583969] 'read index received' (duration: 1.076801273s)","trace[494583969] 'applied index is now lower than readState.Index' (duration: 6.707873ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:14:13.458329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.697557689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-17T06:14:13.458336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082318807s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T06:14:13.458403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.374317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-17T06:14:13.458378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230370871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-17T06:14:13.458399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271161938s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-17T06:14:13.458447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.353764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T06:14:13.458477Z","caller":"traceutil/trace.go:171","msg":"trace[252990731] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1973; }","duration":"950.418276ms","start":"2026-04-17T06:14:12.50805Z","end":"2026-04-17T06:14:13.458468Z","steps":["trace[252990731] 'agreement among raft nodes before linearized reading' (duration: 950.367654ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:14:13.458486Z","caller":"traceutil/trace.go:171","msg":"trace[332479510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1973; }","duration":"1.271304691s","start":"2026-04-17T06:14:12.187159Z","end":"2026-04-17T06:14:13.458464Z","steps":["trace[332479510] 'agreement among raft nodes before linearized reading' (duration: 1.271046765s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:14:13.458523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:14:12.508028Z","time spent":"950.485658ms","remote":"127.0.0.1:43186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-04-17T06:14:13.458542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:14:12.187137Z","time spent":"1.271394933s","remote":"127.0.0.1:42962","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-04-17T06:14:13.45863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03453395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T06:14:13.458395Z","caller":"traceutil/trace.go:171","msg":"trace[1021277924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1973; }","duration":"1.082378658s","start":"2026-04-17T06:14:12.375999Z","end":"2026-04-17T06:14:13.458378Z","steps":["trace[1021277924] 'agreement among raft nodes before linearized reading' (duration: 1.082283626s)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:14:13.458703Z","caller":"traceutil/trace.go:171","msg":"trace[1947207286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1973; }","duration":"1.034630742s","start":"2026-04-17T06:14:12.424042Z","end":"2026-04-17T06:14:13.458673Z","steps":["trace[1947207286] 'agreement among raft nodes before linearized reading' (duration: 1.034463848s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:14:13.45876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:14:12.424025Z","time spent":"1.034722964s","remote":"127.0.0.1:42962","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-04-17T06:14:13.458469Z","caller":"traceutil/trace.go:171","msg":"trace[1313374016] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1973; }","duration":"1.230513375s","start":"2026-04-17T06:14:12.227932Z","end":"2026-04-17T06:14:13.458445Z","steps":["trace[1313374016] 'agreement among raft nodes before linearized reading' (duration: 1.230236118s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:14:13.458811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:14:12.227915Z","time spent":"1.230886234s","remote":"127.0.0.1:42962","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-04-17T06:14:13.458694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:14:12.375932Z","time spent":"1.082754177s","remote":"127.0.0.1:49406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T06:14:13.458426Z","caller":"traceutil/trace.go:171","msg":"trace[775778456] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1973; }","duration":"185.433857ms","start":"2026-04-17T06:14:13.272987Z","end":"2026-04-17T06:14:13.458421Z","steps":["trace[775778456] 'agreement among raft nodes before linearized reading' (duration: 185.390836ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:14:13.458359Z","caller":"traceutil/trace.go:171","msg":"trace[2102873327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1973; }","duration":"1.697629771s","start":"2026-04-17T06:14:11.760719Z","end":"2026-04-17T06:14:13.458349Z","steps":["trace[2102873327] 'agreement among raft nodes before linearized reading' (duration: 1.697493587s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:14:13.458964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:14:11.7607Z","time spent":"1.698248315s","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T06:14:34.685477Z","caller":"traceutil/trace.go:171","msg":"trace[626115712] transaction","detail":"{read_only:false; response_revision:2045; number_of_response:1; }","duration":"211.619006ms","start":"2026-04-17T06:14:34.473836Z","end":"2026-04-17T06:14:34.685455Z","steps":["trace[626115712] 'process raft request' (duration: 211.461252ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:14:36.446282Z","caller":"traceutil/trace.go:171","msg":"trace[815384874] transaction","detail":"{read_only:false; response_revision:2049; number_of_response:1; }","duration":"829.114804ms","start":"2026-04-17T06:14:35.617135Z","end":"2026-04-17T06:14:36.44625Z","steps":["trace[815384874] 'process raft request' (duration: 828.970881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:14:36.446472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:14:35.617078Z","time spent":"829.296838ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:14:36.446498Z","caller":"traceutil/trace.go:171","msg":"trace[699148967] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"829.352709ms","start":"2026-04-17T06:14:35.617116Z","end":"2026-04-17T06:14:36.446468Z","steps":["trace[699148967] 'process raft request' (duration: 751.636331ms)","trace[699148967] 'compare' (duration: 77.020682ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:14:36.446564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:14:35.617056Z","time spent":"829.475443ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:14:36.446636Z","caller":"traceutil/trace.go:171","msg":"trace[396258209] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"829.418492ms","start":"2026-04-17T06:14:35.617188Z","end":"2026-04-17T06:14:36.446607Z","steps":["trace[396258209] 'process raft request' (duration: 829.019722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:14:36.446794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:14:35.61716Z","time spent":"829.575705ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:14:36.446827Z","caller":"traceutil/trace.go:171","msg":"trace[547274193] linearizableReadLoop","detail":"{readStateIndex:2150; appliedIndex:2147; }","duration":"442.204587ms","start":"2026-04-17T06:14:36.004584Z","end":"2026-04-17T06:14:36.446789Z","steps":["trace[547274193] 'read index received' (duration: 364.090889ms)","trace[547274193] 'applied index is now lower than readState.Index' (duration: 78.110718ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:14:36.447367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.431243ms","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-04-17T06:14:36.447433Z","caller":"traceutil/trace.go:171","msg":"trace[1185958874] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2050; }","duration":"442.903934ms","start":"2026-04-17T06:14:36.004513Z","end":"2026-04-17T06:14:36.447417Z","steps":["trace[1185958874] 'agreement among raft nodes before linearized reading' (duration: 442.445613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:14:36.447658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:14:36.004467Z","time spent":"443.129279ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-04-17T06:14:37.70521Z","caller":"traceutil/trace.go:171","msg":"trace[2102832592] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"140.389037ms","start":"2026-04-17T06:14:37.564783Z","end":"2026-04-17T06:14:37.705172Z","steps":["trace[2102832592] 'process raft request' (duration: 140.033269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:14:50.273157Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880868788882608392,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T06:14:50.488071Z","caller":"traceutil/trace.go:171","msg":"trace[1842217417] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"720.147747ms","start":"2026-04-17T06:14:49.767901Z","end":"2026-04-17T06:14:50.488049Z","steps":["trace[1842217417] 'process raft request' (duration: 719.946792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:14:50.488223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:14:49.767887Z","time spent":"720.277042ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:14:50.488317Z","caller":"traceutil/trace.go:171","msg":"trace[696935236] linearizableReadLoop","detail":"{readStateIndex:2250; appliedIndex:2250; }","duration":"716.062918ms","start":"2026-04-17T06:14:49.772221Z","end":"2026-04-17T06:14:50.488284Z","steps":["trace[696935236] 'read index received' (duration: 716.055158ms)","trace[696935236] 'applied index is now lower than readState.Index' (duration: 6.18µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:14:50.488447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.441068ms","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":"warn","ts":"2026-04-17T06:14:50.488461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.252222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T06:14:50.488486Z","caller":"traceutil/trace.go:171","msg":"trace[636713043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2145; }","duration":"716.316663ms","start":"2026-04-17T06:14:49.772162Z","end":"2026-04-17T06:14:50.488478Z","steps":["trace[636713043] 'agreement among raft nodes before linearized reading' (duration: 716.221571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:14:50.488501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:14:49.772143Z","time spent":"716.354805ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T06:14:50.488489Z","caller":"traceutil/trace.go:171","msg":"trace[2144022442] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2145; }","duration":"458.53047ms","start":"2026-04-17T06:14:50.029945Z","end":"2026-04-17T06:14:50.488476Z","steps":["trace[2144022442] 'agreement among raft nodes before linearized reading' (duration: 458.448838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:14:50.488532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:14:50.029923Z","time spent":"458.597881ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-04-17T06:14:50.740854Z","caller":"traceutil/trace.go:171","msg":"trace[1963824933] linearizableReadLoop","detail":"{readStateIndex:2251; appliedIndex:2250; }","duration":"209.662035ms","start":"2026-04-17T06:14:50.531165Z","end":"2026-04-17T06:14:50.740827Z","steps":["trace[1963824933] 'read index received' (duration: 208.815854ms)","trace[1963824933] 'applied index is now lower than readState.Index' (duration: 845.091µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:14:50.740991Z","caller":"traceutil/trace.go:171","msg":"trace[2030083310] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"248.810124ms","start":"2026-04-17T06:14:50.492129Z","end":"2026-04-17T06:14:50.740939Z","steps":["trace[2030083310] 'process raft request' (duration: 247.888181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:14:50.741093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.226389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-17T06:14:50.741097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.906131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-17T06:14:50.741129Z","caller":"traceutil/trace.go:171","msg":"trace[1433065340] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2146; }","duration":"201.303821ms","start":"2026-04-17T06:14:50.539814Z","end":"2026-04-17T06:14:50.741118Z","steps":["trace[1433065340] 'agreement among raft nodes before linearized reading' (duration: 201.150938ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:14:50.741146Z","caller":"traceutil/trace.go:171","msg":"trace[1556761303] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2146; }","duration":"209.992394ms","start":"2026-04-17T06:14:50.531141Z","end":"2026-04-17T06:14:50.741133Z","steps":["trace[1556761303] 'agreement among raft nodes before linearized reading' (duration: 209.822049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:14:50.741287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.24706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T06:14:50.74138Z","caller":"traceutil/trace.go:171","msg":"trace[659970804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2146; }","duration":"199.377523ms","start":"2026-04-17T06:14:50.541982Z","end":"2026-04-17T06:14:50.741359Z","steps":["trace[659970804] 'agreement among raft nodes before linearized reading' (duration: 199.106697ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:14:52.78872Z","caller":"traceutil/trace.go:171","msg":"trace[1244431855] linearizableReadLoop","detail":"{readStateIndex:2257; appliedIndex:2256; }","duration":"177.766434ms","start":"2026-04-17T06:14:52.610931Z","end":"2026-04-17T06:14:52.788698Z","steps":["trace[1244431855] 'read index received' (duration: 177.651612ms)","trace[1244431855] 'applied index is now lower than readState.Index' (duration: 113.702µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:14:52.788789Z","caller":"traceutil/trace.go:171","msg":"trace[1857175994] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"293.904308ms","start":"2026-04-17T06:14:52.494866Z","end":"2026-04-17T06:14:52.788771Z","steps":["trace[1857175994] 'process raft request' (duration: 293.677803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:14:52.788924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.9739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:14:52.788966Z","caller":"traceutil/trace.go:171","msg":"trace[1619203324] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2151; }","duration":"178.062401ms","start":"2026-04-17T06:14:52.610892Z","end":"2026-04-17T06:14:52.788954Z","steps":["trace[1619203324] 'agreement among raft nodes before linearized reading' (duration: 177.975279ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:15:05.022793Z","caller":"traceutil/trace.go:171","msg":"trace[399606705] linearizableReadLoop","detail":"{readStateIndex:2298; appliedIndex:2297; }","duration":"185.80137ms","start":"2026-04-17T06:15:04.836976Z","end":"2026-04-17T06:15:05.022777Z","steps":["trace[399606705] 'read index received' (duration: 185.707308ms)","trace[399606705] 'applied index is now lower than readState.Index' (duration: 93.472µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:15:05.022913Z","caller":"traceutil/trace.go:171","msg":"trace[1380153518] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"189.274856ms","start":"2026-04-17T06:15:04.833588Z","end":"2026-04-17T06:15:05.022863Z","steps":["trace[1380153518] 'process raft request' (duration: 189.094462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:05.023013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.351759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T06:15:05.023031Z","caller":"traceutil/trace.go:171","msg":"trace[1124341573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2190; }","duration":"158.387349ms","start":"2026-04-17T06:15:04.864638Z","end":"2026-04-17T06:15:05.023025Z","steps":["trace[1124341573] 'agreement among raft nodes before linearized reading' (duration: 158.347558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:05.023063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.469922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-17T06:15:05.023076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.081237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-17T06:15:05.02297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.86865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T06:15:05.023136Z","caller":"traceutil/trace.go:171","msg":"trace[1267350248] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2190; }","duration":"145.583306ms","start":"2026-04-17T06:15:04.877535Z","end":"2026-04-17T06:15:05.023119Z","steps":["trace[1267350248] 'agreement among raft nodes before linearized reading' (duration: 145.34322ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:15:05.023161Z","caller":"traceutil/trace.go:171","msg":"trace[914404528] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2190; }","duration":"186.21779ms","start":"2026-04-17T06:15:04.836924Z","end":"2026-04-17T06:15:05.023141Z","steps":["trace[914404528] 'agreement among raft nodes before linearized reading' (duration: 185.936963ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:15:05.023176Z","caller":"traceutil/trace.go:171","msg":"trace[914132565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2190; }","duration":"161.116316ms","start":"2026-04-17T06:15:04.862043Z","end":"2026-04-17T06:15:05.023159Z","steps":["trace[914132565] 'agreement among raft nodes before linearized reading' (duration: 160.826059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:05.023208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.728057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T06:15:05.023282Z","caller":"traceutil/trace.go:171","msg":"trace[686986826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2190; }","duration":"160.820108ms","start":"2026-04-17T06:15:04.862457Z","end":"2026-04-17T06:15:05.023277Z","steps":["trace[686986826] 'agreement among raft nodes before linearized reading' (duration: 160.720086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:05.273149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.011849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:15:05.27327Z","caller":"traceutil/trace.go:171","msg":"trace[1399294259] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"245.133374ms","start":"2026-04-17T06:15:05.028117Z","end":"2026-04-17T06:15:05.273251Z","steps":["trace[1399294259] 'process raft request' (duration: 104.94071ms)","trace[1399294259] 'compare' (duration: 139.879106ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:15:05.598765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.532042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:15:05.598997Z","caller":"traceutil/trace.go:171","msg":"trace[618017891] linearizableReadLoop","detail":"{readStateIndex:2303; appliedIndex:2299; }","duration":"328.861045ms","start":"2026-04-17T06:15:05.27012Z","end":"2026-04-17T06:15:05.598981Z","steps":["trace[618017891] 'read index received' (duration: 196.683707ms)","trace[618017891] 'applied index is now lower than readState.Index' (duration: 132.176448ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:15:05.599161Z","caller":"traceutil/trace.go:171","msg":"trace[1199950090] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"570.935073ms","start":"2026-04-17T06:15:05.02814Z","end":"2026-04-17T06:15:05.599075Z","steps":["trace[1199950090] 'process raft request' (duration: 438.637572ms)","trace[1199950090] 'compare' (duration: 131.392989ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:15:05.599272Z","caller":"traceutil/trace.go:171","msg":"trace[580419036] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"569.882188ms","start":"2026-04-17T06:15:05.029375Z","end":"2026-04-17T06:15:05.599257Z","steps":["trace[580419036] 'process raft request' (duration: 569.54344ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:15:05.599362Z","caller":"traceutil/trace.go:171","msg":"trace[378255978] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"569.430197ms","start":"2026-04-17T06:15:05.029915Z","end":"2026-04-17T06:15:05.599345Z","steps":["trace[378255978] 'process raft request' (duration: 569.032837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:05.599412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:05.029365Z","time spent":"569.95416ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:15:05.59946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:05.029899Z","time spent":"569.51958ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:15:05.599222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.096371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T06:15:05.59983Z","caller":"traceutil/trace.go:171","msg":"trace[133425211] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2195; }","duration":"329.747576ms","start":"2026-04-17T06:15:05.270068Z","end":"2026-04-17T06:15:05.599816Z","steps":["trace[133425211] 'agreement among raft nodes before linearized reading' (duration: 328.968028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:05.59989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:05.27005Z","time spent":"329.823119ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-04-17T06:15:05.600301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:05.028115Z","time spent":"571.20414ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:15:05.600673Z","caller":"traceutil/trace.go:171","msg":"trace[1522331023] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"572.518172ms","start":"2026-04-17T06:15:05.028137Z","end":"2026-04-17T06:15:05.600655Z","steps":["trace[1522331023] 'process raft request' (duration: 570.726288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:05.60093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:05.028112Z","time spent":"572.767968ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:15:11.527446Z","caller":"traceutil/trace.go:171","msg":"trace[1320738171] linearizableReadLoop","detail":"{readStateIndex:2361; appliedIndex:2360; }","duration":"233.093859ms","start":"2026-04-17T06:15:11.294328Z","end":"2026-04-17T06:15:11.527422Z","steps":["trace[1320738171] 'read index received' (duration: 232.887854ms)","trace[1320738171] 'applied index is now lower than readState.Index' (duration: 205.095µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:15:11.527757Z","caller":"traceutil/trace.go:171","msg":"trace[796761073] transaction","detail":"{read_only:false; response_revision:2252; number_of_response:1; }","duration":"487.10337ms","start":"2026-04-17T06:15:11.04064Z","end":"2026-04-17T06:15:11.527744Z","steps":["trace[796761073] 'process raft request' (duration: 486.655019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:11.527878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:11.040615Z","time spent":"487.180472ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:15:11.528055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.714384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-17T06:15:11.528109Z","caller":"traceutil/trace.go:171","msg":"trace[65349952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2252; }","duration":"233.820386ms","start":"2026-04-17T06:15:11.294277Z","end":"2026-04-17T06:15:11.528097Z","steps":["trace[65349952] 'agreement among raft nodes before linearized reading' (duration: 233.518039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:12.047767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.951015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T06:15:12.047843Z","caller":"traceutil/trace.go:171","msg":"trace[692375450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2253; }","duration":"402.041647ms","start":"2026-04-17T06:15:11.645784Z","end":"2026-04-17T06:15:12.047826Z","steps":["trace[692375450] 'range keys from in-memory index tree' (duration: 401.804421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:12.04784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.665175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-17T06:15:12.04784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.743103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-17T06:15:12.047882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:11.645715Z","time spent":"402.15922ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T06:15:12.047908Z","caller":"traceutil/trace.go:171","msg":"trace[483256080] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2253; }","duration":"398.761277ms","start":"2026-04-17T06:15:11.649127Z","end":"2026-04-17T06:15:12.047889Z","steps":["trace[483256080] 'range keys from in-memory index tree' (duration: 398.481311ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:15:12.04791Z","caller":"traceutil/trace.go:171","msg":"trace[1273219366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2253; }","duration":"395.844175ms","start":"2026-04-17T06:15:11.652049Z","end":"2026-04-17T06:15:12.047893Z","steps":["trace[1273219366] 'range keys from in-memory index tree' (duration: 395.541599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:12.047955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:11.652035Z","time spent":"395.908488ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T06:15:12.047921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.871496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-17T06:15:12.047969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:11.649117Z","time spent":"398.841469ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T06:15:12.048017Z","caller":"traceutil/trace.go:171","msg":"trace[1175644533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2253; }","duration":"395.979579ms","start":"2026-04-17T06:15:11.652015Z","end":"2026-04-17T06:15:12.047995Z","steps":["trace[1175644533] 'range keys from in-memory index tree' (duration: 395.682172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:12.048071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:11.651961Z","time spent":"396.099601ms","remote":"127.0.0.1:42962","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-04-17T06:15:12.383982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.061923ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-17T06:15:12.384259Z","caller":"traceutil/trace.go:171","msg":"trace[494229879] linearizableReadLoop","detail":"{readStateIndex:2363; appliedIndex:2362; }","duration":"394.379189ms","start":"2026-04-17T06:15:11.989857Z","end":"2026-04-17T06:15:12.384236Z","steps":["trace[494229879] 'read index received' (duration: 82.053099ms)","trace[494229879] 'applied index is now lower than readState.Index' (duration: 312.32363ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:15:12.384445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.579014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/test-pvc.18a7104ed6a7249b\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-04-17T06:15:12.384471Z","caller":"traceutil/trace.go:171","msg":"trace[1062539159] range","detail":"{range_begin:/registry/events/default/test-pvc.18a7104ed6a7249b; range_end:; response_count:1; response_revision:2253; }","duration":"394.658726ms","start":"2026-04-17T06:15:11.989805Z","end":"2026-04-17T06:15:12.384464Z","steps":["trace[1062539159] 'agreement among raft nodes before linearized reading' (duration: 394.504463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:12.384495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:11.989779Z","time spent":"394.710177ms","remote":"127.0.0.1:49500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":918,"request content":"key:\"/registry/events/default/test-pvc.18a7104ed6a7249b\" "} {"level":"info","ts":"2026-04-17T06:15:12.38511Z","caller":"traceutil/trace.go:171","msg":"trace[1808754182] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"332.308599ms","start":"2026-04-17T06:15:12.052654Z","end":"2026-04-17T06:15:12.384962Z","steps":["trace[1808754182] 'process raft request' (duration: 331.49327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:12.385511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:12.052633Z","time spent":"332.7781ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:15:12.386179Z","caller":"traceutil/trace.go:171","msg":"trace[1950429246] transaction","detail":"{read_only:false; response_revision:2257; number_of_response:1; }","duration":"332.439321ms","start":"2026-04-17T06:15:12.05372Z","end":"2026-04-17T06:15:12.386159Z","steps":["trace[1950429246] 'process raft request' (duration: 332.37241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:12.386293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:12.053676Z","time spent":"332.563864ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:15:12.386521Z","caller":"traceutil/trace.go:171","msg":"trace[1650458129] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"332.860243ms","start":"2026-04-17T06:15:12.053645Z","end":"2026-04-17T06:15:12.386505Z","steps":["trace[1650458129] 'process raft request' (duration: 332.36638ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:15:12.386448Z","caller":"traceutil/trace.go:171","msg":"trace[482403325] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"332.815921ms","start":"2026-04-17T06:15:12.053604Z","end":"2026-04-17T06:15:12.38642Z","steps":["trace[482403325] 'process raft request' (duration: 331.14324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:12.386632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:12.053639Z","time spent":"332.945254ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:15:12.386747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:12.053595Z","time spent":"332.987775ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:15:14.543839Z","caller":"traceutil/trace.go:171","msg":"trace[1102178421] linearizableReadLoop","detail":"{readStateIndex:2372; appliedIndex:2371; }","duration":"153.156311ms","start":"2026-04-17T06:15:14.390656Z","end":"2026-04-17T06:15:14.543813Z","steps":["trace[1102178421] 'read index received' (duration: 152.941556ms)","trace[1102178421] 'applied index is now lower than readState.Index' (duration: 213.455µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:15:14.54396Z","caller":"traceutil/trace.go:171","msg":"trace[385718013] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"463.013321ms","start":"2026-04-17T06:15:14.080915Z","end":"2026-04-17T06:15:14.543928Z","steps":["trace[385718013] 'process raft request' (duration: 462.728794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:14.544134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:14.080891Z","time spent":"463.155755ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:15:14.544204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.526061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T06:15:14.544255Z","caller":"traceutil/trace.go:171","msg":"trace[189299592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2262; }","duration":"153.639024ms","start":"2026-04-17T06:15:14.390602Z","end":"2026-04-17T06:15:14.544241Z","steps":["trace[189299592] 'agreement among raft nodes before linearized reading' (duration: 153.419068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:14.54439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.649604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T06:15:14.544485Z","caller":"traceutil/trace.go:171","msg":"trace[1298615095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2262; }","duration":"153.793747ms","start":"2026-04-17T06:15:14.390673Z","end":"2026-04-17T06:15:14.544467Z","steps":["trace[1298615095] 'agreement among raft nodes before linearized reading' (duration: 153.549461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:14.544698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.483029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-17T06:15:14.544736Z","caller":"traceutil/trace.go:171","msg":"trace[1780433655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2262; }","duration":"153.551331ms","start":"2026-04-17T06:15:14.391176Z","end":"2026-04-17T06:15:14.544727Z","steps":["trace[1780433655] 'agreement among raft nodes before linearized reading' (duration: 153.427828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:14.544922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.112535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T06:15:14.54497Z","caller":"traceutil/trace.go:171","msg":"trace[465990011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2262; }","duration":"154.201567ms","start":"2026-04-17T06:15:14.390755Z","end":"2026-04-17T06:15:14.544957Z","steps":["trace[465990011] 'agreement among raft nodes before linearized reading' (duration: 154.037343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:14.972224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.983899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:15:14.972455Z","caller":"traceutil/trace.go:171","msg":"trace[2099638529] linearizableReadLoop","detail":"{readStateIndex:2376; appliedIndex:2373; }","duration":"318.991113ms","start":"2026-04-17T06:15:14.653448Z","end":"2026-04-17T06:15:14.972439Z","steps":["trace[2099638529] 'read index received' (duration: 37.281µs)","trace[2099638529] 'applied index is now lower than readState.Index' (duration: 318.952712ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:15:14.972531Z","caller":"traceutil/trace.go:171","msg":"trace[766109281] transaction","detail":"{read_only:false; response_revision:2264; number_of_response:1; }","duration":"422.638391ms","start":"2026-04-17T06:15:14.549881Z","end":"2026-04-17T06:15:14.97252Z","steps":["trace[766109281] 'process raft request' (duration: 77.276562ms)","trace[766109281] 'compare' (duration: 344.725853ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:15:14.972592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:14.549862Z","time spent":"422.693583ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:15:14.972839Z","caller":"traceutil/trace.go:171","msg":"trace[1073436356] transaction","detail":"{read_only:false; response_revision:2265; number_of_response:1; }","duration":"422.641951ms","start":"2026-04-17T06:15:14.550185Z","end":"2026-04-17T06:15:14.972827Z","steps":["trace[1073436356] 'process raft request' (duration: 422.137009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:14.972904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:14.550166Z","time spent":"422.706982ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:15:14.972954Z","caller":"traceutil/trace.go:171","msg":"trace[1494548378] transaction","detail":"{read_only:false; response_revision:2266; number_of_response:1; }","duration":"422.456827ms","start":"2026-04-17T06:15:14.550401Z","end":"2026-04-17T06:15:14.972857Z","steps":["trace[1494548378] 'process raft request' (duration: 421.998016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:14.973005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.560616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T06:15:14.973057Z","caller":"traceutil/trace.go:171","msg":"trace[335162077] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2266; }","duration":"319.648979ms","start":"2026-04-17T06:15:14.653394Z","end":"2026-04-17T06:15:14.973043Z","steps":["trace[335162077] 'agreement among raft nodes before linearized reading' (duration: 319.577827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:14.973092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:14.653375Z","time spent":"319.708621ms","remote":"127.0.0.1:41830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-17T06:15:14.973209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:14.550389Z","time spent":"422.679812ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:15:15.307718Z","caller":"traceutil/trace.go:171","msg":"trace[723931612] linearizableReadLoop","detail":"{readStateIndex:2377; appliedIndex:2376; }","duration":"335.20796ms","start":"2026-04-17T06:15:14.97249Z","end":"2026-04-17T06:15:15.307698Z","steps":["trace[723931612] 'read index received' (duration: 259.065785ms)","trace[723931612] 'applied index is now lower than readState.Index' (duration: 76.141115ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:15:15.307899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.526035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:15:15.307767Z","caller":"traceutil/trace.go:171","msg":"trace[38431696] transaction","detail":"{read_only:false; response_revision:2267; number_of_response:1; }","duration":"434.708506ms","start":"2026-04-17T06:15:14.873017Z","end":"2026-04-17T06:15:15.307726Z","steps":["trace[38431696] 'process raft request' (duration: 358.675654ms)","trace[38431696] 'compare' (duration: 75.867778ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:15:15.307978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.431956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:15:15.308051Z","caller":"traceutil/trace.go:171","msg":"trace[1739955813] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:2267; }","duration":"244.526919ms","start":"2026-04-17T06:15:15.063503Z","end":"2026-04-17T06:15:15.30803Z","steps":["trace[1739955813] 'agreement among raft nodes before linearized reading' (duration: 244.436426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:15.308211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:14.872989Z","time spent":"435.114046ms","remote":"127.0.0.1:49500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T06:15:15.307956Z","caller":"traceutil/trace.go:171","msg":"trace[479021642] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2267; }","duration":"279.621147ms","start":"2026-04-17T06:15:15.02832Z","end":"2026-04-17T06:15:15.307941Z","steps":["trace[479021642] 'agreement among raft nodes before linearized reading' (duration: 279.531685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:15.307892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.385111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T06:15:15.308625Z","caller":"traceutil/trace.go:171","msg":"trace[1779695123] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2267; }","duration":"391.174071ms","start":"2026-04-17T06:15:14.917436Z","end":"2026-04-17T06:15:15.30861Z","steps":["trace[1779695123] 'agreement among raft nodes before linearized reading' (duration: 390.337979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:15.308674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:14.917417Z","time spent":"391.238013ms","remote":"127.0.0.1:43110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-17T06:15:15.615371Z","caller":"traceutil/trace.go:171","msg":"trace[1787943691] transaction","detail":"{read_only:false; response_revision:2273; number_of_response:1; }","duration":"270.078575ms","start":"2026-04-17T06:15:15.345233Z","end":"2026-04-17T06:15:15.615312Z","steps":["trace[1787943691] 'process raft request' (duration: 270.010983ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:15:15.615453Z","caller":"traceutil/trace.go:171","msg":"trace[227054569] transaction","detail":"{read_only:false; response_revision:2272; number_of_response:1; }","duration":"270.398593ms","start":"2026-04-17T06:15:15.345025Z","end":"2026-04-17T06:15:15.615423Z","steps":["trace[227054569] 'process raft request' (duration: 183.459653ms)","trace[227054569] 'compare' (duration: 86.648242ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:15:17.474185Z","caller":"traceutil/trace.go:171","msg":"trace[791276503] transaction","detail":"{read_only:false; response_revision:2304; number_of_response:1; }","duration":"106.229842ms","start":"2026-04-17T06:15:17.367927Z","end":"2026-04-17T06:15:17.474157Z","steps":["trace[791276503] 'process raft request' (duration: 106.117499ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:15:17.474254Z","caller":"traceutil/trace.go:171","msg":"trace[2111191870] transaction","detail":"{read_only:false; response_revision:2305; number_of_response:1; }","duration":"105.385621ms","start":"2026-04-17T06:15:17.368855Z","end":"2026-04-17T06:15:17.474241Z","steps":["trace[2111191870] 'process raft request' (duration: 105.255098ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:15:35.887996Z","caller":"traceutil/trace.go:171","msg":"trace[476369114] linearizableReadLoop","detail":"{readStateIndex:2519; appliedIndex:2518; }","duration":"232.695789ms","start":"2026-04-17T06:15:35.655281Z","end":"2026-04-17T06:15:35.887976Z","steps":["trace[476369114] 'read index received' (duration: 232.663018ms)","trace[476369114] 'applied index is now lower than readState.Index' (duration: 31.601µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:15:35.888099Z","caller":"traceutil/trace.go:171","msg":"trace[1154988891] transaction","detail":"{read_only:false; response_revision:2405; number_of_response:1; }","duration":"508.227238ms","start":"2026-04-17T06:15:35.37984Z","end":"2026-04-17T06:15:35.888067Z","steps":["trace[1154988891] 'process raft request' (duration: 508.014493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:35.888197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.903005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-17T06:15:35.888234Z","caller":"traceutil/trace.go:171","msg":"trace[1864766106] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2405; }","duration":"232.986476ms","start":"2026-04-17T06:15:35.655236Z","end":"2026-04-17T06:15:35.888223Z","steps":["trace[1864766106] 'agreement among raft nodes before linearized reading' (duration: 232.852633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:35.888265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:35.379818Z","time spent":"508.366512ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:15:36.291081Z","caller":"traceutil/trace.go:171","msg":"trace[367635485] transaction","detail":"{read_only:false; response_revision:2406; number_of_response:1; }","duration":"519.562686ms","start":"2026-04-17T06:15:35.771484Z","end":"2026-04-17T06:15:36.291046Z","steps":["trace[367635485] 'process raft request' (duration: 434.422691ms)","trace[367635485] 'compare' (duration: 84.688084ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:15:36.291325Z","caller":"traceutil/trace.go:171","msg":"trace[237781805] linearizableReadLoop","detail":"{readStateIndex:2520; appliedIndex:2519; }","duration":"403.235017ms","start":"2026-04-17T06:15:35.888065Z","end":"2026-04-17T06:15:36.2913Z","steps":["trace[237781805] 'read index received' (duration: 317.843255ms)","trace[237781805] 'applied index is now lower than readState.Index' (duration: 85.31889ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:15:36.291559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.407809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-17T06:15:36.291631Z","caller":"traceutil/trace.go:171","msg":"trace[915288541] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2406; }","duration":"505.469991ms","start":"2026-04-17T06:15:35.786128Z","end":"2026-04-17T06:15:36.291598Z","steps":["trace[915288541] 'agreement among raft nodes before linearized reading' (duration: 505.296917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:36.29167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:35.786081Z","time spent":"505.581243ms","remote":"127.0.0.1:42962","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-04-17T06:15:36.291787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:35.771457Z","time spent":"519.695869ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:15:36.291997Z","caller":"traceutil/trace.go:171","msg":"trace[1046111355] transaction","detail":"{read_only:false; response_revision:2407; number_of_response:1; }","duration":"399.845544ms","start":"2026-04-17T06:15:35.892126Z","end":"2026-04-17T06:15:36.291972Z","steps":["trace[1046111355] 'process raft request' (duration: 399.437504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:36.292084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:35.892114Z","time spent":"399.926955ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:15:36.292217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.255687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:15:36.292346Z","caller":"traceutil/trace.go:171","msg":"trace[1082139458] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2407; }","duration":"403.633266ms","start":"2026-04-17T06:15:35.888695Z","end":"2026-04-17T06:15:36.292328Z","steps":["trace[1082139458] 'agreement among raft nodes before linearized reading' (duration: 403.292398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:15:36.292535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:15:35.888677Z","time spent":"403.838691ms","remote":"127.0.0.1:49584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-04-17T06:15:36.893406Z","caller":"traceutil/trace.go:171","msg":"trace[920722703] transaction","detail":"{read_only:false; response_revision:2410; number_of_response:1; }","duration":"134.75136ms","start":"2026-04-17T06:15:36.758623Z","end":"2026-04-17T06:15:36.893374Z","steps":["trace[920722703] 'process raft request' (duration: 105.968025ms)","trace[920722703] 'compare' (duration: 28.55452ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:16:11.098136Z","caller":"traceutil/trace.go:171","msg":"trace[1307328323] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"128.926356ms","start":"2026-04-17T06:16:10.969172Z","end":"2026-04-17T06:16:11.098099Z","steps":["trace[1307328323] 'process raft request' (duration: 128.747392ms)"],"step_count":1}