{"level":"warn","ts":"2026-07-17T13:41:10.116966Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T13:41:10.117044Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-07-17T13:41:10.117101Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T13:41:10.117113Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-07-17T13:41:10.117132Z","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-07-17T13:41:10.117735Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-07-17T13:41:10.117806Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-07-17T13:41:10.122636Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.596889ms"} {"level":"info","ts":"2026-07-17T13:41:10.127966Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-07-17T13:41:10.128027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T13:41:10.128057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-07-17T13:41:10.128076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T13:41:10.128092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-07-17T13:41:10.128137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-07-17T13:41:10.136065Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T13:41:10.137503Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T13:41:10.139264Z","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-07-17T13:41:10.141771Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T13:41:10.142003Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T13:41:10.142146Z","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-07-17T13:41:10.142285Z","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-07-17T13:41:10.142323Z","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-07-17T13:41:10.143047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-07-17T13:41:10.144465Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-07-17T13:41:10.150068Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-07-17T13:41:10.150128Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-07-17T13:41:10.149851Z","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-07-17T13:41:10.151073Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T13:41:10.151348Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T13:41:10.629373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T13:41:10.629445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T13:41:10.629491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-07-17T13:41:10.62951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-07-17T13:41:10.629521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-07-17T13:41:10.629538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-07-17T13:41:10.629557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-07-17T13:41:10.630574Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T13:41:10.631657Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T13:41:10.631722Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T13:41:10.632157Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T13:41:10.632287Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T13:41:10.632323Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T13:41:10.632331Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T13:41:10.632573Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T13:41:10.632607Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T13:41:10.635835Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T13:41:10.638607Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"warn","ts":"2026-07-17T13:41:46.787891Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054090303108227,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T13:41:47.503278Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054090303108227,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T13:41:48.00407Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054090303108227,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T13:41:48.504347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054090303108227,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T13:41:48.692087Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.73385435s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T13:41:48.692571Z","caller":"traceutil/trace.go:171","msg":"trace[97023899] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"2.734396509s","start":"2026-07-17T13:41:45.958149Z","end":"2026-07-17T13:41:48.692546Z","steps":["trace[97023899] 'process raft request' (duration: 2.734222662s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:41:48.693213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:45.958133Z","time spent":"2.734522873s","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:41:48.698876Z","caller":"traceutil/trace.go:171","msg":"trace[531372713] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:537; }","duration":"2.411630869s","start":"2026-07-17T13:41:46.287227Z","end":"2026-07-17T13:41:48.698857Z","steps":["trace[531372713] 'read index received' (duration: 2.405106023s)","trace[531372713] 'applied index is now lower than readState.Index' (duration: 6.523976ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:41:48.699042Z","caller":"traceutil/trace.go:171","msg":"trace[1325435680] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.694731923s","start":"2026-07-17T13:41:47.004297Z","end":"2026-07-17T13:41:48.699029Z","steps":["trace[1325435680] 'process raft request' (duration: 1.694466494s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:41:48.699183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:47.004283Z","time spent":"1.694833826s","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T13:41:48.699458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.211214679s","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-07-17T13:41:48.69961Z","caller":"traceutil/trace.go:171","msg":"trace[31650700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:519; }","duration":"2.211381505s","start":"2026-07-17T13:41:46.488199Z","end":"2026-07-17T13:41:48.69958Z","steps":["trace[31650700] 'agreement among raft nodes before linearized reading' (duration: 2.211149217s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:41:48.699666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:46.488181Z","time spent":"2.211473408s","remote":"127.0.0.1:51498","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-07-17T13:41:48.69973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.653557754s","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-07-17T13:41:48.699793Z","caller":"traceutil/trace.go:171","msg":"trace[2099599782] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:519; }","duration":"1.653679998s","start":"2026-07-17T13:41:47.046098Z","end":"2026-07-17T13:41:48.699778Z","steps":["trace[2099599782] 'agreement among raft nodes before linearized reading' (duration: 1.653535404s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:41:48.699891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:47.046052Z","time spent":"1.653825914s","remote":"127.0.0.1:51296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-07-17T13:41:48.699946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.673560144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T13:41:48.699994Z","caller":"traceutil/trace.go:171","msg":"trace[1140106481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:519; }","duration":"1.673735699s","start":"2026-07-17T13:41:47.026246Z","end":"2026-07-17T13:41:48.699982Z","steps":["trace[1140106481] 'agreement among raft nodes before linearized reading' (duration: 1.673659016s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:41:48.700026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:47.026229Z","time spent":"1.673786261s","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T13:41:48.700176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.693377426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:41:48.700213Z","caller":"traceutil/trace.go:171","msg":"trace[1410378334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"1.693427638s","start":"2026-07-17T13:41:47.006776Z","end":"2026-07-17T13:41:48.700204Z","steps":["trace[1410378334] 'agreement among raft nodes before linearized reading' (duration: 1.693372546s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:41:48.700183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.412956972s","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-07-17T13:41:48.700291Z","caller":"traceutil/trace.go:171","msg":"trace[415134805] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:519; }","duration":"2.413063886s","start":"2026-07-17T13:41:46.287216Z","end":"2026-07-17T13:41:48.70028Z","steps":["trace[415134805] 'agreement among raft nodes before linearized reading' (duration: 2.412922031s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:41:48.700345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:46.28716Z","time spent":"2.4131766s","remote":"127.0.0.1:51498","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-07-17T13:41:48.70024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:47.00677Z","time spent":"1.693463949s","remote":"127.0.0.1:51212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T13:41:52.850291Z","caller":"traceutil/trace.go:171","msg":"trace[581479992] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"794.941756ms","start":"2026-07-17T13:41:52.055321Z","end":"2026-07-17T13:41:52.850263Z","steps":["trace[581479992] 'process raft request' (duration: 794.738019ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:41:52.850222Z","caller":"traceutil/trace.go:171","msg":"trace[992249584] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"480.088267ms","start":"2026-07-17T13:41:52.370109Z","end":"2026-07-17T13:41:52.850197Z","steps":["trace[992249584] 'read index received' (duration: 479.87907ms)","trace[992249584] 'applied index is now lower than readState.Index' (duration: 208.106µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:41:52.850415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:52.055306Z","time spent":"795.06202ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T13:41:52.850544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.417547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:41:52.850598Z","caller":"traceutil/trace.go:171","msg":"trace[643309493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"480.528721ms","start":"2026-07-17T13:41:52.370054Z","end":"2026-07-17T13:41:52.850583Z","steps":["trace[643309493] 'agreement among raft nodes before linearized reading' (duration: 480.380786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:41:52.850666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:52.370031Z","time spent":"480.617684ms","remote":"127.0.0.1:51212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T13:41:54.369308Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054090303108425,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T13:41:54.870418Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054090303108425,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T13:41:55.356814Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.842157406s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T13:41:55.357473Z","caller":"traceutil/trace.go:171","msg":"trace[394704881] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"1.488389472s","start":"2026-07-17T13:41:53.869061Z","end":"2026-07-17T13:41:55.35745Z","steps":["trace[394704881] 'read index received' (duration: 1.487876126s)","trace[394704881] 'applied index is now lower than readState.Index' (duration: 512.646µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:41:55.35807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488999261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-17T13:41:55.358127Z","caller":"traceutil/trace.go:171","msg":"trace[531338170] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:612; }","duration":"1.489100044s","start":"2026-07-17T13:41:53.869005Z","end":"2026-07-17T13:41:55.358105Z","steps":["trace[531338170] 'agreement among raft nodes before linearized reading' (duration: 1.488530157s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:41:55.358158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:53.868983Z","time spent":"1.489167687s","remote":"127.0.0.1:51224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"warn","ts":"2026-07-17T13:41:55.358563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.95162ms","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-07-17T13:41:55.358612Z","caller":"traceutil/trace.go:171","msg":"trace[1324258437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:612; }","duration":"483.020321ms","start":"2026-07-17T13:41:54.875579Z","end":"2026-07-17T13:41:55.358599Z","steps":["trace[1324258437] 'agreement among raft nodes before linearized reading' (duration: 482.696031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:41:55.358623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.613519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-17T13:41:55.358675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.522157ms","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-07-17T13:41:55.35864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:54.875567Z","time spent":"483.060023ms","remote":"127.0.0.1:51498","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-07-17T13:41:55.358708Z","caller":"traceutil/trace.go:171","msg":"trace[1900004080] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:612; }","duration":"482.735582ms","start":"2026-07-17T13:41:54.875939Z","end":"2026-07-17T13:41:55.358674Z","steps":["trace[1900004080] 'agreement among raft nodes before linearized reading' (duration: 482.353671ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:41:55.358747Z","caller":"traceutil/trace.go:171","msg":"trace[308340787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:612; }","duration":"483.606211ms","start":"2026-07-17T13:41:54.875108Z","end":"2026-07-17T13:41:55.358714Z","steps":["trace[308340787] 'agreement among raft nodes before linearized reading' (duration: 483.24676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:41:55.358754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:54.875915Z","time spent":"482.828626ms","remote":"127.0.0.1:51498","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-07-17T13:41:55.358829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:54.875093Z","time spent":"483.678012ms","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T13:41:55.358857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.512027ms","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-07-17T13:41:55.358897Z","caller":"traceutil/trace.go:171","msg":"trace[556356597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:612; }","duration":"492.586439ms","start":"2026-07-17T13:41:54.866292Z","end":"2026-07-17T13:41:55.358879Z","steps":["trace[556356597] 'agreement among raft nodes before linearized reading' (duration: 492.490286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:41:55.358919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:54.866274Z","time spent":"492.639511ms","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T13:41:55.359235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.081372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:41:55.359302Z","caller":"traceutil/trace.go:171","msg":"trace[220269378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"987.194905ms","start":"2026-07-17T13:41:54.372088Z","end":"2026-07-17T13:41:55.359283Z","steps":["trace[220269378] 'agreement among raft nodes before linearized reading' (duration: 986.981408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:41:55.35934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:54.372074Z","time spent":"987.258197ms","remote":"127.0.0.1:51212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T13:41:55.361721Z","caller":"traceutil/trace.go:171","msg":"trace[525891794] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"1.844595063s","start":"2026-07-17T13:41:53.512935Z","end":"2026-07-17T13:41:55.35753Z","steps":["trace[525891794] 'process raft request' (duration: 1.844231032s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:41:55.361849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:53.512902Z","time spent":"1.848889855s","remote":"127.0.0.1:51296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T13:41:56.222137Z","caller":"traceutil/trace.go:171","msg":"trace[660735369] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"391.371176ms","start":"2026-07-17T13:41:55.830725Z","end":"2026-07-17T13:41:56.222096Z","steps":["trace[660735369] 'process raft request' (duration: 389.987114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:41:56.222367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:55.830699Z","time spent":"391.491309ms","remote":"127.0.0.1:51296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:41:56.952924Z","caller":"traceutil/trace.go:171","msg":"trace[1052367609] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"240.824117ms","start":"2026-07-17T13:41:56.712069Z","end":"2026-07-17T13:41:56.952893Z","steps":["trace[1052367609] 'process raft request' (duration: 240.128766ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:41:59.779738Z","caller":"traceutil/trace.go:171","msg":"trace[37001800] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"233.220259ms","start":"2026-07-17T13:41:59.54649Z","end":"2026-07-17T13:41:59.779711Z","steps":["trace[37001800] 'process raft request' (duration: 233.023813ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:41:59.805222Z","caller":"traceutil/trace.go:171","msg":"trace[2111335515] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"215.518418ms","start":"2026-07-17T13:41:59.589682Z","end":"2026-07-17T13:41:59.8052Z","steps":["trace[2111335515] 'process raft request' (duration: 215.426625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:42:00.216797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.491875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T13:42:00.217121Z","caller":"traceutil/trace.go:171","msg":"trace[1331761267] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"354.026943ms","start":"2026-07-17T13:41:59.863073Z","end":"2026-07-17T13:42:00.2171Z","steps":["trace[1331761267] 'process raft request' (duration: 353.971382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:42:00.217263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:59.863065Z","time spent":"354.142406ms","remote":"127.0.0.1:51402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:42:00.217469Z","caller":"traceutil/trace.go:171","msg":"trace[1541304222] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"355.054644ms","start":"2026-07-17T13:41:59.862386Z","end":"2026-07-17T13:42:00.217441Z","steps":["trace[1541304222] 'process raft request' (duration: 354.531008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:42:00.217599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:59.862379Z","time spent":"355.169047ms","remote":"127.0.0.1:51284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T13:42:00.21751Z","caller":"traceutil/trace.go:171","msg":"trace[828242377] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"354.712914ms","start":"2026-07-17T13:41:59.862789Z","end":"2026-07-17T13:42:00.217502Z","steps":["trace[828242377] 'process raft request' (duration: 354.189328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:42:00.21772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:59.86278Z","time spent":"354.915789ms","remote":"127.0.0.1:51456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:42:00.21792Z","caller":"traceutil/trace.go:171","msg":"trace[2144284672] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"355.835626ms","start":"2026-07-17T13:41:59.862071Z","end":"2026-07-17T13:42:00.217906Z","steps":["trace[2144284672] 'process raft request' (duration: 51.004203ms)","trace[2144284672] 'compare' (duration: 303.335979ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:42:00.218068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:41:59.86204Z","time spent":"355.95844ms","remote":"127.0.0.1:51284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T13:42:15.377992Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054090303108794,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T13:42:15.878941Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054090303108794,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T13:42:15.958374Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.171942992s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T13:42:15.958666Z","caller":"traceutil/trace.go:171","msg":"trace[505021718] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"1.081411431s","start":"2026-07-17T13:42:14.877237Z","end":"2026-07-17T13:42:15.958649Z","steps":["trace[505021718] 'read index received' (duration: 1.081250937s)","trace[505021718] 'applied index is now lower than readState.Index' (duration: 159.854µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:42:15.95884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081571025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:42:15.958931Z","caller":"traceutil/trace.go:171","msg":"trace[473488537] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:792; }","duration":"1.081716389s","start":"2026-07-17T13:42:14.877193Z","end":"2026-07-17T13:42:15.958909Z","steps":["trace[473488537] 'agreement among raft nodes before linearized reading' (duration: 1.081544645s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:42:15.958984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:42:14.877173Z","time spent":"1.081799061s","remote":"127.0.0.1:51296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/envoy-gateway\" "} {"level":"warn","ts":"2026-07-17T13:42:15.958977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.240916ms","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-07-17T13:42:15.959006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.583702ms","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-07-17T13:42:15.959049Z","caller":"traceutil/trace.go:171","msg":"trace[407742827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"415.640143ms","start":"2026-07-17T13:42:15.543401Z","end":"2026-07-17T13:42:15.959041Z","steps":["trace[407742827] 'agreement among raft nodes before linearized reading' (duration: 415.50841ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:42:15.95905Z","caller":"traceutil/trace.go:171","msg":"trace[1703323681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"485.365659ms","start":"2026-07-17T13:42:15.473666Z","end":"2026-07-17T13:42:15.959031Z","steps":["trace[1703323681] 'agreement among raft nodes before linearized reading' (duration: 485.144433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:42:15.959076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:42:15.543344Z","time spent":"415.726316ms","remote":"127.0.0.1:51498","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-07-17T13:42:15.959057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.427424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-17T13:42:15.959041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.298912ms","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-07-17T13:42:15.959093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:42:15.473649Z","time spent":"485.43337ms","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T13:42:15.959127Z","caller":"traceutil/trace.go:171","msg":"trace[1102701291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:792; }","duration":"484.555338ms","start":"2026-07-17T13:42:15.474554Z","end":"2026-07-17T13:42:15.959109Z","steps":["trace[1102701291] 'agreement among raft nodes before linearized reading' (duration: 484.364063ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:42:15.959146Z","caller":"traceutil/trace.go:171","msg":"trace[673037502] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:792; }","duration":"488.455706ms","start":"2026-07-17T13:42:15.470672Z","end":"2026-07-17T13:42:15.959127Z","steps":["trace[673037502] 'agreement among raft nodes before linearized reading' (duration: 488.1966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:42:15.959171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:42:15.474547Z","time spent":"484.611329ms","remote":"127.0.0.1:51498","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-07-17T13:42:15.959193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:42:15.470657Z","time spent":"488.523538ms","remote":"127.0.0.1:51498","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-07-17T13:42:15.95922Z","caller":"traceutil/trace.go:171","msg":"trace[319486992] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"1.172852595s","start":"2026-07-17T13:42:14.78635Z","end":"2026-07-17T13:42:15.959202Z","steps":["trace[319486992] 'process raft request' (duration: 1.172190439s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:42:15.959324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:42:14.786338Z","time spent":"1.172913726s","remote":"127.0.0.1:51402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T13:42:15.959434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.103504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-17T13:42:15.959474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.110776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T13:42:15.959482Z","caller":"traceutil/trace.go:171","msg":"trace[326240591] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"760.180975ms","start":"2026-07-17T13:42:15.199287Z","end":"2026-07-17T13:42:15.959468Z","steps":["trace[326240591] 'agreement among raft nodes before linearized reading' (duration: 760.045171ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:42:15.959508Z","caller":"traceutil/trace.go:171","msg":"trace[290382575] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:792; }","duration":"855.816993ms","start":"2026-07-17T13:42:15.103682Z","end":"2026-07-17T13:42:15.959499Z","steps":["trace[290382575] 'agreement among raft nodes before linearized reading' (duration: 855.108916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:42:15.959522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:42:15.199269Z","time spent":"760.242707ms","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T13:42:15.959533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:42:15.103658Z","time spent":"855.866635ms","remote":"127.0.0.1:51700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-07-17T13:42:58.173179Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054090303109740,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T13:42:58.680289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054090303109740,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T13:42:59.034186Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.366307627s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T13:42:59.035445Z","caller":"traceutil/trace.go:171","msg":"trace[1980193180] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"1.367354686s","start":"2026-07-17T13:42:57.667725Z","end":"2026-07-17T13:42:59.03508Z","steps":["trace[1980193180] 'process raft request' (duration: 1.366863077s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:42:59.036383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:42:57.667704Z","time spent":"1.367808084s","remote":"127.0.0.1:51284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T13:42:59.069049Z","caller":"traceutil/trace.go:171","msg":"trace[1571012496] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1343; }","duration":"1.396100164s","start":"2026-07-17T13:42:57.672901Z","end":"2026-07-17T13:42:59.069002Z","steps":["trace[1571012496] 'read index received' (duration: 1.362588421s)","trace[1571012496] 'applied index is now lower than readState.Index' (duration: 33.511173ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:42:59.069156Z","caller":"traceutil/trace.go:171","msg":"trace[331442104] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"1.401057832s","start":"2026-07-17T13:42:57.668041Z","end":"2026-07-17T13:42:59.069099Z","steps":["trace[331442104] 'process raft request' (duration: 1.400818088s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:42:59.069305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:42:57.668032Z","time spent":"1.401200424s","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:42:59.069336Z","caller":"traceutil/trace.go:171","msg":"trace[1510133147] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"1.399628447s","start":"2026-07-17T13:42:57.669698Z","end":"2026-07-17T13:42:59.069326Z","steps":["trace[1510133147] 'process raft request' (duration: 1.399273991s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:42:59.069425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:42:57.669688Z","time spent":"1.399672298s","remote":"127.0.0.1:34444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1093,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T13:42:59.069469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.396563132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:42:59.069497Z","caller":"traceutil/trace.go:171","msg":"trace[1183573449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1301; }","duration":"1.396613683s","start":"2026-07-17T13:42:57.672874Z","end":"2026-07-17T13:42:59.069488Z","steps":["trace[1183573449] 'agreement among raft nodes before linearized reading' (duration: 1.396575592s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:42:59.069513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:42:57.672866Z","time spent":"1.396642523s","remote":"127.0.0.1:51212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T13:43:48.45111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.477678ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T13:43:48.451347Z","caller":"traceutil/trace.go:171","msg":"trace[1444637796] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"190.327964ms","start":"2026-07-17T13:43:48.260989Z","end":"2026-07-17T13:43:48.451317Z","steps":["trace[1444637796] 'process raft request' (duration: 61.555672ms)","trace[1444637796] 'compare' (duration: 128.351377ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:43:48.451446Z","caller":"traceutil/trace.go:171","msg":"trace[805364099] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"188.56669ms","start":"2026-07-17T13:43:48.262847Z","end":"2026-07-17T13:43:48.451414Z","steps":["trace[805364099] 'process raft request' (duration: 188.358118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:44:01.618242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.220574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T13:44:01.61837Z","caller":"traceutil/trace.go:171","msg":"trace[1033828427] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"290.975556ms","start":"2026-07-17T13:44:01.327363Z","end":"2026-07-17T13:44:01.618339Z","steps":["trace[1033828427] 'process raft request' (duration: 50.58914ms)","trace[1033828427] 'compare' (duration: 239.889979ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:44:01.618551Z","caller":"traceutil/trace.go:171","msg":"trace[1398141022] linearizableReadLoop","detail":"{readStateIndex:2271; appliedIndex:2270; }","duration":"242.24715ms","start":"2026-07-17T13:44:01.376292Z","end":"2026-07-17T13:44:01.618539Z","steps":["trace[1398141022] 'read index received' (duration: 1.649991ms)","trace[1398141022] 'applied index is now lower than readState.Index' (duration: 240.579799ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:44:01.618644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.341481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-04eae0bd-ffda-4168-8baa-41cb6293033f\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-07-17T13:44:01.61867Z","caller":"traceutil/trace.go:171","msg":"trace[624519374] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-04eae0bd-ffda-4168-8baa-41cb6293033f; range_end:; response_count:1; response_revision:2186; }","duration":"242.411203ms","start":"2026-07-17T13:44:01.376252Z","end":"2026-07-17T13:44:01.618663Z","steps":["trace[624519374] 'agreement among raft nodes before linearized reading' (duration: 242.342362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:44:01.618848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.140086ms","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-07-17T13:44:01.618865Z","caller":"traceutil/trace.go:171","msg":"trace[373631954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2186; }","duration":"228.183666ms","start":"2026-07-17T13:44:01.390677Z","end":"2026-07-17T13:44:01.61886Z","steps":["trace[373631954] 'agreement among raft nodes before linearized reading' (duration: 228.112215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:44:01.618887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.615454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T13:44:01.61894Z","caller":"traceutil/trace.go:171","msg":"trace[1399465062] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2186; }","duration":"219.687915ms","start":"2026-07-17T13:44:01.399237Z","end":"2026-07-17T13:44:01.618925Z","steps":["trace[1399465062] 'agreement among raft nodes before linearized reading' (duration: 219.532713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:44:01.618956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.319693ms","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-07-17T13:44:01.619016Z","caller":"traceutil/trace.go:171","msg":"trace[566851143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2186; }","duration":"233.408224ms","start":"2026-07-17T13:44:01.385591Z","end":"2026-07-17T13:44:01.618999Z","steps":["trace[566851143] 'agreement among raft nodes before linearized reading' (duration: 233.245482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:44:01.619048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.632334ms","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-07-17T13:44:01.619072Z","caller":"traceutil/trace.go:171","msg":"trace[1973941547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2186; }","duration":"242.667195ms","start":"2026-07-17T13:44:01.376398Z","end":"2026-07-17T13:44:01.619065Z","steps":["trace[1973941547] 'agreement among raft nodes before linearized reading' (duration: 242.365331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:44:01.619105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.706006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-17T13:44:01.619149Z","caller":"traceutil/trace.go:171","msg":"trace[1864371644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2186; }","duration":"209.783637ms","start":"2026-07-17T13:44:01.409355Z","end":"2026-07-17T13:44:01.619139Z","steps":["trace[1864371644] 'agreement among raft nodes before linearized reading' (duration: 209.613145ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:44:29.780623Z","caller":"traceutil/trace.go:171","msg":"trace[334010831] linearizableReadLoop","detail":"{readStateIndex:2519; appliedIndex:2518; }","duration":"109.70101ms","start":"2026-07-17T13:44:29.670897Z","end":"2026-07-17T13:44:29.780598Z","steps":["trace[334010831] 'read index received' (duration: 109.610059ms)","trace[334010831] 'applied index is now lower than readState.Index' (duration: 89.921µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:44:29.780697Z","caller":"traceutil/trace.go:171","msg":"trace[2072980685] transaction","detail":"{read_only:false; response_revision:2426; number_of_response:1; }","duration":"118.014111ms","start":"2026-07-17T13:44:29.662639Z","end":"2026-07-17T13:44:29.780653Z","steps":["trace[2072980685] 'process raft request' (duration: 117.817888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:44:29.780838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.921904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T13:44:29.781242Z","caller":"traceutil/trace.go:171","msg":"trace[1065638207] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2426; }","duration":"110.371788ms","start":"2026-07-17T13:44:29.670854Z","end":"2026-07-17T13:44:29.781226Z","steps":["trace[1065638207] 'agreement among raft nodes before linearized reading' (duration: 109.857362ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:44:29.855364Z","caller":"traceutil/trace.go:171","msg":"trace[1048353355] transaction","detail":"{read_only:false; response_revision:2427; number_of_response:1; }","duration":"130.065516ms","start":"2026-07-17T13:44:29.725274Z","end":"2026-07-17T13:44:29.855339Z","steps":["trace[1048353355] 'process raft request' (duration: 113.012791ms)","trace[1048353355] 'compare' (duration: 16.929904ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:44:29.921171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.417041ms","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-07-17T13:44:29.921426Z","caller":"traceutil/trace.go:171","msg":"trace[311376244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2428; }","duration":"116.731166ms","start":"2026-07-17T13:44:29.804673Z","end":"2026-07-17T13:44:29.921405Z","steps":["trace[311376244] 'agreement among raft nodes before linearized reading' (duration: 116.339631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:44:29.9216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.904478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-17T13:44:29.921643Z","caller":"traceutil/trace.go:171","msg":"trace[156197274] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2428; }","duration":"111.965218ms","start":"2026-07-17T13:44:29.80967Z","end":"2026-07-17T13:44:29.921636Z","steps":["trace[156197274] 'agreement among raft nodes before linearized reading' (duration: 111.873767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:44:29.9219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.141331ms","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-07-17T13:44:29.921986Z","caller":"traceutil/trace.go:171","msg":"trace[827325965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2428; }","duration":"112.264083ms","start":"2026-07-17T13:44:29.809704Z","end":"2026-07-17T13:44:29.921968Z","steps":["trace[827325965] 'agreement among raft nodes before linearized reading' (duration: 111.372372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:44:29.921778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.900531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T13:44:29.922506Z","caller":"traceutil/trace.go:171","msg":"trace[960096733] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2428; }","duration":"114.379768ms","start":"2026-07-17T13:44:29.807827Z","end":"2026-07-17T13:44:29.922206Z","steps":["trace[960096733] 'agreement among raft nodes before linearized reading' (duration: 113.733349ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:44:31.801041Z","caller":"traceutil/trace.go:171","msg":"trace[2012988747] transaction","detail":"{read_only:false; response_revision:2437; number_of_response:1; }","duration":"395.657331ms","start":"2026-07-17T13:44:31.405352Z","end":"2026-07-17T13:44:31.80101Z","steps":["trace[2012988747] 'process raft request' (duration: 395.55481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:44:31.801185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:44:31.405329Z","time spent":"395.769233ms","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:45:45.788431Z","caller":"traceutil/trace.go:171","msg":"trace[1983451906] transaction","detail":"{read_only:false; response_revision:3013; number_of_response:1; }","duration":"105.892975ms","start":"2026-07-17T13:45:45.68251Z","end":"2026-07-17T13:45:45.788403Z","steps":["trace[1983451906] 'process raft request' (duration: 105.73017ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:46:22.592533Z","caller":"traceutil/trace.go:171","msg":"trace[797555087] transaction","detail":"{read_only:false; response_revision:3250; number_of_response:1; }","duration":"277.569632ms","start":"2026-07-17T13:46:22.314944Z","end":"2026-07-17T13:46:22.592514Z","steps":["trace[797555087] 'process raft request' (duration: 277.417218ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:46:22.596889Z","caller":"traceutil/trace.go:171","msg":"trace[519587067] linearizableReadLoop","detail":"{readStateIndex:3369; appliedIndex:3366; }","duration":"105.154896ms","start":"2026-07-17T13:46:22.491708Z","end":"2026-07-17T13:46:22.596863Z","steps":["trace[519587067] 'read index received' (duration: 100.706573ms)","trace[519587067] 'applied index is now lower than readState.Index' (duration: 4.447283ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:46:22.597015Z","caller":"traceutil/trace.go:171","msg":"trace[1635731215] transaction","detail":"{read_only:false; response_revision:3252; number_of_response:1; }","duration":"213.023478ms","start":"2026-07-17T13:46:22.383974Z","end":"2026-07-17T13:46:22.596998Z","steps":["trace[1635731215] 'process raft request' (duration: 212.806222ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:46:22.597004Z","caller":"traceutil/trace.go:171","msg":"trace[1800628149] transaction","detail":"{read_only:false; response_revision:3251; number_of_response:1; }","duration":"281.829979ms","start":"2026-07-17T13:46:22.315115Z","end":"2026-07-17T13:46:22.596945Z","steps":["trace[1800628149] 'process raft request' (duration: 281.536221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:46:22.597203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.476503ms","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-07-17T13:46:22.597254Z","caller":"traceutil/trace.go:171","msg":"trace[1633074861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3252; }","duration":"105.575386ms","start":"2026-07-17T13:46:22.491666Z","end":"2026-07-17T13:46:22.597241Z","steps":["trace[1633074861] 'agreement among raft nodes before linearized reading' (duration: 105.32879ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:19.469973Z","caller":"traceutil/trace.go:171","msg":"trace[1513594653] linearizableReadLoop","detail":"{readStateIndex:3739; appliedIndex:3738; }","duration":"197.641067ms","start":"2026-07-17T13:47:19.272203Z","end":"2026-07-17T13:47:19.469844Z","steps":["trace[1513594653] 'read index received' (duration: 162.237811ms)","trace[1513594653] 'applied index is now lower than readState.Index' (duration: 35.401546ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:47:19.470158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.945014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-17T13:47:19.470262Z","caller":"traceutil/trace.go:171","msg":"trace[178821087] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3609; }","duration":"198.100457ms","start":"2026-07-17T13:47:19.27214Z","end":"2026-07-17T13:47:19.470241Z","steps":["trace[178821087] 'agreement among raft nodes before linearized reading' (duration: 197.853002ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:19.756765Z","caller":"traceutil/trace.go:171","msg":"trace[179371745] transaction","detail":"{read_only:false; response_revision:3610; number_of_response:1; }","duration":"281.552481ms","start":"2026-07-17T13:47:19.47519Z","end":"2026-07-17T13:47:19.756743Z","steps":["trace[179371745] 'process raft request' (duration: 281.405579ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:19.758887Z","caller":"traceutil/trace.go:171","msg":"trace[597043210] linearizableReadLoop","detail":"{readStateIndex:3740; appliedIndex:3740; }","duration":"282.045101ms","start":"2026-07-17T13:47:19.476826Z","end":"2026-07-17T13:47:19.758871Z","steps":["trace[597043210] 'read index received' (duration: 282.040861ms)","trace[597043210] 'applied index is now lower than readState.Index' (duration: 3.4µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:47:19.759085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.242555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-07-17T13:47:19.759119Z","caller":"traceutil/trace.go:171","msg":"trace[1249873738] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3610; }","duration":"282.288846ms","start":"2026-07-17T13:47:19.476821Z","end":"2026-07-17T13:47:19.75911Z","steps":["trace[1249873738] 'agreement among raft nodes before linearized reading' (duration: 282.120872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:47:20.259386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054090303114680,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T13:47:20.640512Z","caller":"traceutil/trace.go:171","msg":"trace[860949555] transaction","detail":"{read_only:false; response_revision:3611; number_of_response:1; }","duration":"1.110733911s","start":"2026-07-17T13:47:19.529733Z","end":"2026-07-17T13:47:20.640467Z","steps":["trace[860949555] 'process raft request' (duration: 1.050287979s)","trace[860949555] 'compare' (duration: 60.050434ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:47:20.64066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:47:19.529723Z","time spent":"1.110863094s","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:47:20.641816Z","caller":"traceutil/trace.go:171","msg":"trace[1646920269] transaction","detail":"{read_only:false; response_revision:3612; number_of_response:1; }","duration":"881.578681ms","start":"2026-07-17T13:47:19.760207Z","end":"2026-07-17T13:47:20.641786Z","steps":["trace[1646920269] 'process raft request' (duration: 880.217262ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:20.641909Z","caller":"traceutil/trace.go:171","msg":"trace[810670857] linearizableReadLoop","detail":"{readStateIndex:3743; appliedIndex:3740; }","duration":"882.951899ms","start":"2026-07-17T13:47:19.758927Z","end":"2026-07-17T13:47:20.641879Z","steps":["trace[810670857] 'read index received' (duration: 821.076757ms)","trace[810670857] 'applied index is now lower than readState.Index' (duration: 61.873892ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:47:20.642027Z","caller":"traceutil/trace.go:171","msg":"trace[1390423596] transaction","detail":"{read_only:false; response_revision:3613; number_of_response:1; }","duration":"546.673266ms","start":"2026-07-17T13:47:20.09534Z","end":"2026-07-17T13:47:20.642014Z","steps":["trace[1390423596] 'process raft request' (duration: 546.36937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:47:20.64208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:47:19.760189Z","time spent":"881.748734ms","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T13:47:20.642146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:47:20.09532Z","time spent":"546.746638ms","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":482,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:47:20.642321Z","caller":"traceutil/trace.go:171","msg":"trace[696972190] transaction","detail":"{read_only:false; response_revision:3614; number_of_response:1; }","duration":"224.074003ms","start":"2026-07-17T13:47:20.418226Z","end":"2026-07-17T13:47:20.6423Z","steps":["trace[696972190] 'process raft request' (duration: 223.579823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:47:20.642331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.19322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7462"} {"level":"info","ts":"2026-07-17T13:47:20.642385Z","caller":"traceutil/trace.go:171","msg":"trace[1902383691] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:3614; }","duration":"878.279281ms","start":"2026-07-17T13:47:19.764093Z","end":"2026-07-17T13:47:20.642373Z","steps":["trace[1902383691] 'agreement among raft nodes before linearized reading' (duration: 878.108127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:47:20.642416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:47:19.764077Z","time spent":"878.333292ms","remote":"127.0.0.1:51708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":7485,"request content":"key:\"/registry/statefulsets/auth-system/keycloak\" "} {"level":"warn","ts":"2026-07-17T13:47:20.642231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113192262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:47:20.644594Z","caller":"traceutil/trace.go:171","msg":"trace[591391600] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:3614; }","duration":"1.113694273s","start":"2026-07-17T13:47:19.52889Z","end":"2026-07-17T13:47:20.642584Z","steps":["trace[591391600] 'agreement among raft nodes before linearized reading' (duration: 1.113125621s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:47:20.644692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:47:19.528874Z","time spent":"1.115778196s","remote":"127.0.0.1:39554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-07-17T13:47:21.19203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.978332ms","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-07-17T13:47:21.192096Z","caller":"traceutil/trace.go:171","msg":"trace[1638913156] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3620; }","duration":"155.086994ms","start":"2026-07-17T13:47:21.036994Z","end":"2026-07-17T13:47:21.192081Z","steps":["trace[1638913156] 'range keys from in-memory index tree' (duration: 154.803388ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:22.985196Z","caller":"traceutil/trace.go:171","msg":"trace[1492880677] transaction","detail":"{read_only:false; response_revision:3627; number_of_response:1; }","duration":"117.879589ms","start":"2026-07-17T13:47:22.867286Z","end":"2026-07-17T13:47:22.985165Z","steps":["trace[1492880677] 'process raft request' (duration: 105.685988ms)","trace[1492880677] 'compare' (duration: 11.887264ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:47:22.985379Z","caller":"traceutil/trace.go:171","msg":"trace[1414351243] transaction","detail":"{read_only:false; response_revision:3628; number_of_response:1; }","duration":"117.187574ms","start":"2026-07-17T13:47:22.868077Z","end":"2026-07-17T13:47:22.985265Z","steps":["trace[1414351243] 'process raft request' (duration: 116.907018ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:24.781514Z","caller":"traceutil/trace.go:171","msg":"trace[6544828] transaction","detail":"{read_only:false; response_revision:3638; number_of_response:1; }","duration":"119.210482ms","start":"2026-07-17T13:47:24.662281Z","end":"2026-07-17T13:47:24.781492Z","steps":["trace[6544828] 'process raft request' (duration: 119.015138ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:24.807544Z","caller":"traceutil/trace.go:171","msg":"trace[1157510738] transaction","detail":"{read_only:false; response_revision:3639; number_of_response:1; }","duration":"103.218025ms","start":"2026-07-17T13:47:24.704302Z","end":"2026-07-17T13:47:24.80752Z","steps":["trace[1157510738] 'process raft request' (duration: 103.021051ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:24.807998Z","caller":"traceutil/trace.go:171","msg":"trace[1104975273] transaction","detail":"{read_only:false; response_revision:3640; number_of_response:1; }","duration":"103.653033ms","start":"2026-07-17T13:47:24.704323Z","end":"2026-07-17T13:47:24.807976Z","steps":["trace[1104975273] 'process raft request' (duration: 103.146173ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:25.475543Z","caller":"traceutil/trace.go:171","msg":"trace[1416904394] transaction","detail":"{read_only:false; response_revision:3649; number_of_response:1; }","duration":"102.529974ms","start":"2026-07-17T13:47:25.37298Z","end":"2026-07-17T13:47:25.47551Z","steps":["trace[1416904394] 'process raft request' (duration: 102.312419ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:27.278409Z","caller":"traceutil/trace.go:171","msg":"trace[1136210594] linearizableReadLoop","detail":"{readStateIndex:3789; appliedIndex:3788; }","duration":"155.645301ms","start":"2026-07-17T13:47:27.122744Z","end":"2026-07-17T13:47:27.278389Z","steps":["trace[1136210594] 'read index received' (duration: 60.905447ms)","trace[1136210594] 'applied index is now lower than readState.Index' (duration: 94.738924ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:47:27.278492Z","caller":"traceutil/trace.go:171","msg":"trace[1108227281] transaction","detail":"{read_only:false; response_revision:3658; number_of_response:1; }","duration":"181.881404ms","start":"2026-07-17T13:47:27.096539Z","end":"2026-07-17T13:47:27.278421Z","steps":["trace[1108227281] 'process raft request' (duration: 87.120339ms)","trace[1108227281] 'compare' (duration: 94.636433ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:47:27.278788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.035189ms","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-07-17T13:47:27.27895Z","caller":"traceutil/trace.go:171","msg":"trace[742074090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3658; }","duration":"156.256833ms","start":"2026-07-17T13:47:27.122683Z","end":"2026-07-17T13:47:27.27894Z","steps":["trace[742074090] 'agreement among raft nodes before linearized reading' (duration: 155.824895ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:29.39593Z","caller":"traceutil/trace.go:171","msg":"trace[275458046] transaction","detail":"{read_only:false; response_revision:3670; number_of_response:1; }","duration":"109.890418ms","start":"2026-07-17T13:47:29.28602Z","end":"2026-07-17T13:47:29.395911Z","steps":["trace[275458046] 'process raft request' (duration: 109.732275ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:30.971326Z","caller":"traceutil/trace.go:171","msg":"trace[623715074] transaction","detail":"{read_only:false; response_revision:3675; number_of_response:1; }","duration":"118.221861ms","start":"2026-07-17T13:47:30.853076Z","end":"2026-07-17T13:47:30.971298Z","steps":["trace[623715074] 'process raft request' (duration: 81.579592ms)","trace[623715074] 'compare' (duration: 36.349363ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:47:31.00366Z","caller":"traceutil/trace.go:171","msg":"trace[2050009351] transaction","detail":"{read_only:false; response_revision:3676; number_of_response:1; }","duration":"137.981439ms","start":"2026-07-17T13:47:30.865648Z","end":"2026-07-17T13:47:31.00363Z","steps":["trace[2050009351] 'process raft request' (duration: 137.836936ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:33.11341Z","caller":"traceutil/trace.go:171","msg":"trace[1260876568] transaction","detail":"{read_only:false; response_revision:3688; number_of_response:1; }","duration":"138.017618ms","start":"2026-07-17T13:47:32.975358Z","end":"2026-07-17T13:47:33.113376Z","steps":["trace[1260876568] 'process raft request' (duration: 67.778075ms)","trace[1260876568] 'compare' (duration: 70.012318ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:47:33.113563Z","caller":"traceutil/trace.go:171","msg":"trace[750030384] transaction","detail":"{read_only:false; response_revision:3689; number_of_response:1; }","duration":"105.429817ms","start":"2026-07-17T13:47:33.008102Z","end":"2026-07-17T13:47:33.113532Z","steps":["trace[750030384] 'process raft request' (duration: 105.203942ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:33.323265Z","caller":"traceutil/trace.go:171","msg":"trace[1743809221] transaction","detail":"{read_only:false; response_revision:3691; number_of_response:1; }","duration":"106.001927ms","start":"2026-07-17T13:47:33.21723Z","end":"2026-07-17T13:47:33.323232Z","steps":["trace[1743809221] 'process raft request' (duration: 77.732003ms)","trace[1743809221] 'compare' (duration: 28.106841ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:47:33.350987Z","caller":"traceutil/trace.go:171","msg":"trace[724997647] transaction","detail":"{read_only:false; response_revision:3692; number_of_response:1; }","duration":"122.536108ms","start":"2026-07-17T13:47:33.228426Z","end":"2026-07-17T13:47:33.350962Z","steps":["trace[724997647] 'process raft request' (duration: 122.363485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:47:33.801204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.484077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T13:47:33.801342Z","caller":"traceutil/trace.go:171","msg":"trace[249220922] linearizableReadLoop","detail":"{readStateIndex:3828; appliedIndex:3827; }","duration":"121.410495ms","start":"2026-07-17T13:47:33.679908Z","end":"2026-07-17T13:47:33.801319Z","steps":["trace[249220922] 'read index received' (duration: 17.625992ms)","trace[249220922] 'applied index is now lower than readState.Index' (duration: 103.782313ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:47:33.801446Z","caller":"traceutil/trace.go:171","msg":"trace[1347248318] transaction","detail":"{read_only:false; response_revision:3696; number_of_response:1; }","duration":"171.897124ms","start":"2026-07-17T13:47:33.629532Z","end":"2026-07-17T13:47:33.801429Z","steps":["trace[1347248318] 'process raft request' (duration: 68.09933ms)","trace[1347248318] 'compare' (duration: 103.329514ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:47:33.801624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.704692ms","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-07-17T13:47:33.801689Z","caller":"traceutil/trace.go:171","msg":"trace[1443813674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3696; }","duration":"121.805414ms","start":"2026-07-17T13:47:33.679865Z","end":"2026-07-17T13:47:33.801671Z","steps":["trace[1443813674] 'agreement among raft nodes before linearized reading' (duration: 121.518747ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:34.113206Z","caller":"traceutil/trace.go:171","msg":"trace[641755994] transaction","detail":"{read_only:false; response_revision:3698; number_of_response:1; }","duration":"167.730184ms","start":"2026-07-17T13:47:33.945434Z","end":"2026-07-17T13:47:34.113164Z","steps":["trace[641755994] 'process raft request' (duration: 167.565251ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:35.316735Z","caller":"traceutil/trace.go:171","msg":"trace[1836142135] transaction","detail":"{read_only:false; response_revision:3703; number_of_response:1; }","duration":"109.918193ms","start":"2026-07-17T13:47:35.206798Z","end":"2026-07-17T13:47:35.316717Z","steps":["trace[1836142135] 'process raft request' (duration: 43.646518ms)","trace[1836142135] 'compare' (duration: 65.753615ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:47:35.529619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.712359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T13:47:35.529736Z","caller":"traceutil/trace.go:171","msg":"trace[610684081] transaction","detail":"{read_only:false; response_revision:3704; number_of_response:1; }","duration":"202.682766ms","start":"2026-07-17T13:47:35.32703Z","end":"2026-07-17T13:47:35.529713Z","steps":["trace[610684081] 'process raft request' (duration: 96.800143ms)","trace[610684081] 'compare' (duration: 105.608337ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:47:35.5429Z","caller":"traceutil/trace.go:171","msg":"trace[71394416] linearizableReadLoop","detail":"{readStateIndex:3838; appliedIndex:3836; }","duration":"172.582848ms","start":"2026-07-17T13:47:35.370301Z","end":"2026-07-17T13:47:35.542884Z","steps":["trace[71394416] 'read index received' (duration: 53.400511ms)","trace[71394416] 'applied index is now lower than readState.Index' (duration: 119.181617ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:47:35.543006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.6883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:47:35.543103Z","caller":"traceutil/trace.go:171","msg":"trace[671074126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3705; }","duration":"172.822673ms","start":"2026-07-17T13:47:35.370271Z","end":"2026-07-17T13:47:35.543094Z","steps":["trace[671074126] 'agreement among raft nodes before linearized reading' (duration: 172.69313ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:35.543014Z","caller":"traceutil/trace.go:171","msg":"trace[160339795] transaction","detail":"{read_only:false; response_revision:3705; number_of_response:1; }","duration":"185.73232ms","start":"2026-07-17T13:47:35.357263Z","end":"2026-07-17T13:47:35.542996Z","steps":["trace[160339795] 'process raft request' (duration: 185.505765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:47:35.581568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.346061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-17T13:47:35.58176Z","caller":"traceutil/trace.go:171","msg":"trace[1435600832] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3706; }","duration":"200.579966ms","start":"2026-07-17T13:47:35.38116Z","end":"2026-07-17T13:47:35.58174Z","steps":["trace[1435600832] 'agreement among raft nodes before linearized reading' (duration: 200.246619ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:35.581533Z","caller":"traceutil/trace.go:171","msg":"trace[258581801] transaction","detail":"{read_only:false; response_revision:3706; number_of_response:1; }","duration":"120.368822ms","start":"2026-07-17T13:47:35.461075Z","end":"2026-07-17T13:47:35.581443Z","steps":["trace[258581801] 'process raft request' (duration: 120.102327ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:35.710829Z","caller":"traceutil/trace.go:171","msg":"trace[1019447534] transaction","detail":"{read_only:false; response_revision:3707; number_of_response:1; }","duration":"124.568134ms","start":"2026-07-17T13:47:35.586214Z","end":"2026-07-17T13:47:35.710782Z","steps":["trace[1019447534] 'process raft request' (duration: 44.308369ms)","trace[1019447534] 'compare' (duration: 80.074371ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:47:37.422382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.505604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-17T13:47:37.422478Z","caller":"traceutil/trace.go:171","msg":"trace[1848397789] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3714; }","duration":"150.629116ms","start":"2026-07-17T13:47:37.271811Z","end":"2026-07-17T13:47:37.42244Z","steps":["trace[1848397789] 'range keys from in-memory index tree' (duration: 150.31953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:47:37.422405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.140282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T13:47:37.422588Z","caller":"traceutil/trace.go:171","msg":"trace[1568875100] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:3714; }","duration":"131.359265ms","start":"2026-07-17T13:47:37.291182Z","end":"2026-07-17T13:47:37.422541Z","steps":["trace[1568875100] 'count revisions from in-memory index tree' (duration: 131.032639ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:37.443641Z","caller":"traceutil/trace.go:171","msg":"trace[1223643518] transaction","detail":"{read_only:false; response_revision:3715; number_of_response:1; }","duration":"121.107594ms","start":"2026-07-17T13:47:37.322516Z","end":"2026-07-17T13:47:37.443624Z","steps":["trace[1223643518] 'process raft request' (duration: 120.967011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:47:37.869757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.302673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-17T13:47:37.869859Z","caller":"traceutil/trace.go:171","msg":"trace[854613671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3717; }","duration":"132.460957ms","start":"2026-07-17T13:47:37.737372Z","end":"2026-07-17T13:47:37.869833Z","steps":["trace[854613671] 'range keys from in-memory index tree' (duration: 132.072459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:47:37.87001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.999823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-17T13:47:37.870079Z","caller":"traceutil/trace.go:171","msg":"trace[2068828056] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3717; }","duration":"154.141886ms","start":"2026-07-17T13:47:37.715922Z","end":"2026-07-17T13:47:37.870064Z","steps":["trace[2068828056] 'range keys from in-memory index tree' (duration: 153.80144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:47:38.236648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.463847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T13:47:38.236742Z","caller":"traceutil/trace.go:171","msg":"trace[1098072756] linearizableReadLoop","detail":"{readStateIndex:3853; appliedIndex:3852; }","duration":"302.713579ms","start":"2026-07-17T13:47:37.934012Z","end":"2026-07-17T13:47:38.236726Z","steps":["trace[1098072756] 'read index received' (duration: 54.065308ms)","trace[1098072756] 'applied index is now lower than readState.Index' (duration: 248.647121ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:47:38.236857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.301587ms","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-07-17T13:47:38.236827Z","caller":"traceutil/trace.go:171","msg":"trace[425447789] transaction","detail":"{read_only:false; response_revision:3720; number_of_response:1; }","duration":"316.810847ms","start":"2026-07-17T13:47:37.919945Z","end":"2026-07-17T13:47:38.236756Z","steps":["trace[425447789] 'process raft request' (duration: 68.148066ms)","trace[425447789] 'compare' (duration: 248.360776ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:47:38.236899Z","caller":"traceutil/trace.go:171","msg":"trace[1203559184] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:3720; }","duration":"239.369138ms","start":"2026-07-17T13:47:37.997515Z","end":"2026-07-17T13:47:38.236885Z","steps":["trace[1203559184] 'agreement among raft nodes before linearized reading' (duration: 239.301527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:47:38.236955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.936104ms","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-07-17T13:47:38.237006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:47:37.919922Z","time spent":"316.992881ms","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:47:38.237041Z","caller":"traceutil/trace.go:171","msg":"trace[1697836820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3720; }","duration":"303.073476ms","start":"2026-07-17T13:47:37.93395Z","end":"2026-07-17T13:47:38.237023Z","steps":["trace[1697836820] 'agreement among raft nodes before linearized reading' (duration: 302.837341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:47:38.237086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:47:37.933926Z","time spent":"303.150968ms","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T13:47:38.419Z","caller":"traceutil/trace.go:171","msg":"trace[2086002590] transaction","detail":"{read_only:false; response_revision:3721; number_of_response:1; }","duration":"177.847855ms","start":"2026-07-17T13:47:38.241128Z","end":"2026-07-17T13:47:38.418976Z","steps":["trace[2086002590] 'process raft request' (duration: 177.704182ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:38.440963Z","caller":"traceutil/trace.go:171","msg":"trace[2046742784] transaction","detail":"{read_only:false; response_revision:3722; number_of_response:1; }","duration":"199.178545ms","start":"2026-07-17T13:47:38.241759Z","end":"2026-07-17T13:47:38.440938Z","steps":["trace[2046742784] 'process raft request' (duration: 199.054703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:47:39.524699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.639188ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-17T13:47:39.525498Z","caller":"traceutil/trace.go:171","msg":"trace[1537109033] transaction","detail":"{read_only:false; response_revision:3726; number_of_response:1; }","duration":"340.665436ms","start":"2026-07-17T13:47:39.184735Z","end":"2026-07-17T13:47:39.5254Z","steps":["trace[1537109033] 'process raft request' (duration: 340.088295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:47:39.52563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:47:39.184712Z","time spent":"340.853589ms","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":482,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:47:39.582308Z","caller":"traceutil/trace.go:171","msg":"trace[943669473] transaction","detail":"{read_only:false; response_revision:3728; number_of_response:1; }","duration":"132.249779ms","start":"2026-07-17T13:47:39.45003Z","end":"2026-07-17T13:47:39.58228Z","steps":["trace[943669473] 'process raft request' (duration: 132.088766ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:39.58249Z","caller":"traceutil/trace.go:171","msg":"trace[960648748] linearizableReadLoop","detail":"{readStateIndex:3862; appliedIndex:3859; }","duration":"308.108346ms","start":"2026-07-17T13:47:39.274334Z","end":"2026-07-17T13:47:39.582442Z","steps":["trace[960648748] 'read index received' (duration: 6.733293ms)","trace[960648748] 'applied index is now lower than readState.Index' (duration: 301.371723ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:47:39.582714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.575198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T13:47:39.582756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.422561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-17T13:47:39.58278Z","caller":"traceutil/trace.go:171","msg":"trace[903164125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3728; }","duration":"212.68369ms","start":"2026-07-17T13:47:39.370077Z","end":"2026-07-17T13:47:39.58276Z","steps":["trace[903164125] 'agreement among raft nodes before linearized reading' (duration: 212.591498ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:39.582825Z","caller":"traceutil/trace.go:171","msg":"trace[2058976950] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3728; }","duration":"308.513943ms","start":"2026-07-17T13:47:39.274278Z","end":"2026-07-17T13:47:39.582792Z","steps":["trace[2058976950] 'agreement among raft nodes before linearized reading' (duration: 308.31313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:47:39.58288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:47:39.274257Z","time spent":"308.609515ms","remote":"127.0.0.1:51408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-07-17T13:47:39.5824Z","caller":"traceutil/trace.go:171","msg":"trace[398692267] transaction","detail":"{read_only:false; response_revision:3727; number_of_response:1; }","duration":"356.867003ms","start":"2026-07-17T13:47:39.22551Z","end":"2026-07-17T13:47:39.582377Z","steps":["trace[398692267] 'process raft request' (duration: 299.875994ms)","trace[398692267] 'compare' (duration: 56.552321ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:47:39.58332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:47:39.22549Z","time spent":"357.672399ms","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T13:47:39.7944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.527855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T13:47:39.794604Z","caller":"traceutil/trace.go:171","msg":"trace[1523129682] transaction","detail":"{read_only:false; response_revision:3729; number_of_response:1; }","duration":"185.519776ms","start":"2026-07-17T13:47:39.609062Z","end":"2026-07-17T13:47:39.794582Z","steps":["trace[1523129682] 'process raft request' (duration: 79.743366ms)","trace[1523129682] 'compare' (duration: 105.27193ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:47:39.794825Z","caller":"traceutil/trace.go:171","msg":"trace[1129292735] transaction","detail":"{read_only:false; response_revision:3730; number_of_response:1; }","duration":"171.875598ms","start":"2026-07-17T13:47:39.622932Z","end":"2026-07-17T13:47:39.794808Z","steps":["trace[1129292735] 'process raft request' (duration: 171.590662ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:47:39.79511Z","caller":"traceutil/trace.go:171","msg":"trace[1585357753] transaction","detail":"{read_only:false; response_revision:3731; number_of_response:1; }","duration":"135.35704ms","start":"2026-07-17T13:47:39.659744Z","end":"2026-07-17T13:47:39.795101Z","steps":["trace[1585357753] 'process raft request' (duration: 134.971452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:11.329649Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054090303115446,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T13:48:11.830868Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054090303115446,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T13:48:11.858521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000828294s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-17T13:48:11.858688Z","caller":"traceutil/trace.go:171","msg":"trace[2116200093] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001012187s","start":"2026-07-17T13:48:09.857647Z","end":"2026-07-17T13:48:11.858659Z","steps":["trace[2116200093] 'agreement among raft nodes before linearized reading' (duration: 2.000844824s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:11.858756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:09.85764Z","time spent":"2.001108368s","remote":"127.0.0.1:51212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T13:48:12.216628Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.366711267s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T13:48:12.217198Z","caller":"traceutil/trace.go:171","msg":"trace[366478238] transaction","detail":"{read_only:false; response_revision:3908; number_of_response:1; }","duration":"2.36738552s","start":"2026-07-17T13:48:09.849795Z","end":"2026-07-17T13:48:12.21718Z","steps":["trace[366478238] 'process raft request' (duration: 2.367237057s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.217301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:09.849771Z","time spent":"2.367458141s","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:48:12.249454Z","caller":"traceutil/trace.go:171","msg":"trace[964652683] linearizableReadLoop","detail":"{readStateIndex:4056; appliedIndex:4048; }","duration":"2.395320926s","start":"2026-07-17T13:48:09.854114Z","end":"2026-07-17T13:48:12.249435Z","steps":["trace[964652683] 'read index received' (duration: 2.36300242s)","trace[964652683] 'applied index is now lower than readState.Index' (duration: 32.317636ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:48:12.249489Z","caller":"traceutil/trace.go:171","msg":"trace[905284233] transaction","detail":"{read_only:false; response_revision:3911; number_of_response:1; }","duration":"2.391903694s","start":"2026-07-17T13:48:09.857548Z","end":"2026-07-17T13:48:12.249451Z","steps":["trace[905284233] 'process raft request' (duration: 2.39167692s)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:48:12.249553Z","caller":"traceutil/trace.go:171","msg":"trace[1287975371] transaction","detail":"{read_only:false; response_revision:3915; number_of_response:1; }","duration":"891.692254ms","start":"2026-07-17T13:48:11.357853Z","end":"2026-07-17T13:48:12.249545Z","steps":["trace[1287975371] 'process raft request' (duration: 891.48402ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:48:12.249541Z","caller":"traceutil/trace.go:171","msg":"trace[367844406] transaction","detail":"{read_only:false; response_revision:3914; number_of_response:1; }","duration":"2.350019129s","start":"2026-07-17T13:48:09.899479Z","end":"2026-07-17T13:48:12.249498Z","steps":["trace[367844406] 'process raft request' (duration: 2.349825895s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.249639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:09.857526Z","time spent":"2.392033846s","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":483,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:48:12.249678Z","caller":"traceutil/trace.go:171","msg":"trace[2129698820] transaction","detail":"{read_only:false; response_revision:3916; number_of_response:1; }","duration":"392.497127ms","start":"2026-07-17T13:48:11.857174Z","end":"2026-07-17T13:48:12.249671Z","steps":["trace[2129698820] 'process raft request' (duration: 392.190941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.249715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:11.857158Z","time spent":"392.538417ms","remote":"127.0.0.1:51284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":888,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T13:48:12.249749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:09.899454Z","time spent":"2.350199933s","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:48:12.249782Z","caller":"traceutil/trace.go:171","msg":"trace[2078264095] transaction","detail":"{read_only:false; response_revision:3910; number_of_response:1; }","duration":"2.399904769s","start":"2026-07-17T13:48:09.849867Z","end":"2026-07-17T13:48:12.249772Z","steps":["trace[2078264095] 'process raft request' (duration: 2.399325738s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.249876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:09.849846Z","time spent":"2.399994661s","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:48:12.249915Z","caller":"traceutil/trace.go:171","msg":"trace[60300248] transaction","detail":"{read_only:false; response_revision:3917; number_of_response:1; }","duration":"387.233021ms","start":"2026-07-17T13:48:11.862675Z","end":"2026-07-17T13:48:12.249908Z","steps":["trace[60300248] 'process raft request' (duration: 386.726972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.249645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:11.357843Z","time spent":"891.735774ms","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T13:48:12.249957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:11.862665Z","time spent":"387.270872ms","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:48:12.249981Z","caller":"traceutil/trace.go:171","msg":"trace[749465437] transaction","detail":"{read_only:false; response_revision:3912; number_of_response:1; }","duration":"2.36819618s","start":"2026-07-17T13:48:09.881777Z","end":"2026-07-17T13:48:12.249973Z","steps":["trace[749465437] 'process raft request' (duration: 2.367476527s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.25005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:09.881752Z","time spent":"2.368270771s","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:48:12.250068Z","caller":"traceutil/trace.go:171","msg":"trace[1898387835] transaction","detail":"{read_only:false; response_revision:3913; number_of_response:1; }","duration":"2.35234517s","start":"2026-07-17T13:48:09.897696Z","end":"2026-07-17T13:48:12.250042Z","steps":["trace[1898387835] 'process raft request' (duration: 2.351584807s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.250136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.153027725s","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-07-17T13:48:12.250178Z","caller":"traceutil/trace.go:171","msg":"trace[1488584041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3917; }","duration":"2.153071866s","start":"2026-07-17T13:48:10.097099Z","end":"2026-07-17T13:48:12.250171Z","steps":["trace[1488584041] 'agreement among raft nodes before linearized reading' (duration: 2.152960913s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.250197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:10.097052Z","time spent":"2.153139367s","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T13:48:12.250186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:09.897681Z","time spent":"2.352437282s","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T13:48:12.25023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.396113631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T13:48:12.250254Z","caller":"traceutil/trace.go:171","msg":"trace[2109464783] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:3917; }","duration":"2.396182672s","start":"2026-07-17T13:48:09.854065Z","end":"2026-07-17T13:48:12.250248Z","steps":["trace[2109464783] 'agreement among raft nodes before linearized reading' (duration: 2.395937277s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.250273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:09.854028Z","time spent":"2.396239013s","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":339,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-07-17T13:48:12.25029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.718603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:48:12.25033Z","caller":"traceutil/trace.go:171","msg":"trace[2105056957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3917; }","duration":"892.761823ms","start":"2026-07-17T13:48:11.357555Z","end":"2026-07-17T13:48:12.250317Z","steps":["trace[2105056957] 'agreement among raft nodes before linearized reading' (duration: 892.703962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.250369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:11.357548Z","time spent":"892.812914ms","remote":"127.0.0.1:51212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T13:48:12.25042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.811524ms","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":"warn","ts":"2026-07-17T13:48:12.250436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.882965ms","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-07-17T13:48:12.250448Z","caller":"traceutil/trace.go:171","msg":"trace[2104325345] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:3917; }","duration":"892.862485ms","start":"2026-07-17T13:48:11.357578Z","end":"2026-07-17T13:48:12.250441Z","steps":["trace[2104325345] 'agreement among raft nodes before linearized reading' (duration: 892.816934ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:48:12.250485Z","caller":"traceutil/trace.go:171","msg":"trace[543289808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3917; }","duration":"892.957926ms","start":"2026-07-17T13:48:11.357518Z","end":"2026-07-17T13:48:12.250476Z","steps":["trace[543289808] 'agreement among raft nodes before linearized reading' (duration: 892.871885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.250499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:11.357574Z","time spent":"892.918215ms","remote":"127.0.0.1:51348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-07-17T13:48:12.25051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:11.357503Z","time spent":"893.000278ms","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T13:48:12.250566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.983007ms","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-07-17T13:48:12.250588Z","caller":"traceutil/trace.go:171","msg":"trace[398584540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3917; }","duration":"893.017198ms","start":"2026-07-17T13:48:11.357564Z","end":"2026-07-17T13:48:12.250582Z","steps":["trace[398584540] 'agreement among raft nodes before linearized reading' (duration: 892.950667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.250606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:11.357561Z","time spent":"893.040238ms","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T13:48:12.250647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.290212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-17T13:48:12.250669Z","caller":"traceutil/trace.go:171","msg":"trace[887269778] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3917; }","duration":"893.320433ms","start":"2026-07-17T13:48:11.357342Z","end":"2026-07-17T13:48:12.250663Z","steps":["trace[887269778] 'agreement among raft nodes before linearized reading' (duration: 893.253092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.250679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.076359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-17T13:48:12.250698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:11.357339Z","time spent":"893.343184ms","remote":"127.0.0.1:51408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-07-17T13:48:12.250717Z","caller":"traceutil/trace.go:171","msg":"trace[1756703071] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3917; }","duration":"893.120759ms","start":"2026-07-17T13:48:11.357585Z","end":"2026-07-17T13:48:12.250706Z","steps":["trace[1756703071] 'agreement among raft nodes before linearized reading' (duration: 893.014907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.250739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:11.357581Z","time spent":"893.15183ms","remote":"127.0.0.1:51498","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-07-17T13:48:12.250739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.255643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T13:48:12.250796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.994379924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T13:48:12.250822Z","caller":"traceutil/trace.go:171","msg":"trace[869427413] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:3917; }","duration":"152.380045ms","start":"2026-07-17T13:48:12.098433Z","end":"2026-07-17T13:48:12.250813Z","steps":["trace[869427413] 'agreement among raft nodes before linearized reading' (duration: 152.178432ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:48:12.250844Z","caller":"traceutil/trace.go:171","msg":"trace[135647133] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:3917; }","duration":"1.994458045s","start":"2026-07-17T13:48:10.256371Z","end":"2026-07-17T13:48:12.250829Z","steps":["trace[135647133] 'agreement among raft nodes before linearized reading' (duration: 1.994376784s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.250899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.079388514s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-17T13:48:12.250905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:10.256356Z","time spent":"1.994521966s","remote":"127.0.0.1:60498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-07-17T13:48:12.250923Z","caller":"traceutil/trace.go:171","msg":"trace[1583288395] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3917; }","duration":"2.079448725s","start":"2026-07-17T13:48:10.171468Z","end":"2026-07-17T13:48:12.250917Z","steps":["trace[1583288395] 'agreement among raft nodes before linearized reading' (duration: 2.079318223s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.250945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:10.17145Z","time spent":"2.079489305s","remote":"127.0.0.1:51498","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-07-17T13:48:12.251098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.942224ms","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-07-17T13:48:12.251121Z","caller":"traceutil/trace.go:171","msg":"trace[1404338653] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:3917; }","duration":"893.991596ms","start":"2026-07-17T13:48:11.357122Z","end":"2026-07-17T13:48:12.251114Z","steps":["trace[1404338653] 'agreement among raft nodes before linearized reading' (duration: 893.955825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.251139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:11.357109Z","time spent":"894.023976ms","remote":"127.0.0.1:51428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-07-17T13:48:12.251127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.808052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T13:48:12.251168Z","caller":"traceutil/trace.go:171","msg":"trace[655209181] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:3917; }","duration":"893.862712ms","start":"2026-07-17T13:48:11.357296Z","end":"2026-07-17T13:48:12.251158Z","steps":["trace[655209181] 'agreement among raft nodes before linearized reading' (duration: 893.778921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.251201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:11.35729Z","time spent":"893.903174ms","remote":"127.0.0.1:51408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":23,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-07-17T13:48:12.251584Z","caller":"traceutil/trace.go:171","msg":"trace[556337731] transaction","detail":"{read_only:false; response_revision:3909; number_of_response:1; }","duration":"2.401705172s","start":"2026-07-17T13:48:09.849864Z","end":"2026-07-17T13:48:12.251569Z","steps":["trace[556337731] 'process raft request' (duration: 2.399220736s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.251652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:09.849846Z","time spent":"2.401768323s","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T13:48:12.253071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.469508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:48:12.253112Z","caller":"traceutil/trace.go:171","msg":"trace[1192009353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3917; }","duration":"393.829001ms","start":"2026-07-17T13:48:11.859275Z","end":"2026-07-17T13:48:12.253104Z","steps":["trace[1192009353] 'agreement among raft nodes before linearized reading' (duration: 391.479308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:12.254839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:48:11.859264Z","time spent":"395.560261ms","remote":"127.0.0.1:51202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T13:48:56.545988Z","caller":"traceutil/trace.go:171","msg":"trace[1428994312] linearizableReadLoop","detail":"{readStateIndex:4407; appliedIndex:4406; }","duration":"176.732802ms","start":"2026-07-17T13:48:56.369232Z","end":"2026-07-17T13:48:56.545965Z","steps":["trace[1428994312] 'read index received' (duration: 155.993µs)","trace[1428994312] 'applied index is now lower than readState.Index' (duration: 176.575999ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:48:56.546077Z","caller":"traceutil/trace.go:171","msg":"trace[1822603531] transaction","detail":"{read_only:false; response_revision:4254; number_of_response:1; }","duration":"177.004287ms","start":"2026-07-17T13:48:56.369063Z","end":"2026-07-17T13:48:56.546067Z","steps":["trace[1822603531] 'process raft request' (duration: 176.776723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:48:56.546148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.893685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:48:56.546212Z","caller":"traceutil/trace.go:171","msg":"trace[92156484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4254; }","duration":"176.991026ms","start":"2026-07-17T13:48:56.369206Z","end":"2026-07-17T13:48:56.546197Z","steps":["trace[92156484] 'agreement among raft nodes before linearized reading' (duration: 176.877524ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:48:56.546357Z","caller":"traceutil/trace.go:171","msg":"trace[726846051] transaction","detail":"{read_only:false; response_revision:4256; number_of_response:1; }","duration":"106.027826ms","start":"2026-07-17T13:48:56.440322Z","end":"2026-07-17T13:48:56.54635Z","steps":["trace[726846051] 'process raft request' (duration: 105.979545ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:48:56.546441Z","caller":"traceutil/trace.go:171","msg":"trace[495077336] transaction","detail":"{read_only:false; response_revision:4255; number_of_response:1; }","duration":"111.561557ms","start":"2026-07-17T13:48:56.434815Z","end":"2026-07-17T13:48:56.546376Z","steps":["trace[495077336] 'process raft request' (duration: 111.448425ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:48:56.547485Z","caller":"traceutil/trace.go:171","msg":"trace[2021838754] transaction","detail":"{read_only:false; response_revision:4257; number_of_response:1; }","duration":"106.851869ms","start":"2026-07-17T13:48:56.440427Z","end":"2026-07-17T13:48:56.547279Z","steps":["trace[2021838754] 'process raft request' (duration: 105.895523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:49:19.045449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.628573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-17T13:49:19.045575Z","caller":"traceutil/trace.go:171","msg":"trace[689596180] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4473; }","duration":"162.832416ms","start":"2026-07-17T13:49:18.882728Z","end":"2026-07-17T13:49:19.04556Z","steps":["trace[689596180] 'range keys from in-memory index tree' (duration: 162.498551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:49:19.045609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.233045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T13:49:19.045706Z","caller":"traceutil/trace.go:171","msg":"trace[1101303976] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4473; }","duration":"249.375038ms","start":"2026-07-17T13:49:18.796309Z","end":"2026-07-17T13:49:19.045684Z","steps":["trace[1101303976] 'count revisions from in-memory index tree' (duration: 249.171153ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:49:58.054237Z","caller":"traceutil/trace.go:171","msg":"trace[1660515440] linearizableReadLoop","detail":"{readStateIndex:5060; appliedIndex:5059; }","duration":"111.044764ms","start":"2026-07-17T13:49:57.943168Z","end":"2026-07-17T13:49:58.054212Z","steps":["trace[1660515440] 'read index received' (duration: 99.65974ms)","trace[1660515440] 'applied index is now lower than readState.Index' (duration: 11.384044ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:49:58.054606Z","caller":"traceutil/trace.go:171","msg":"trace[424886845] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4856; }","duration":"112.939044ms","start":"2026-07-17T13:49:57.941636Z","end":"2026-07-17T13:49:58.054575Z","steps":["trace[424886845] 'process raft request' (duration: 101.141793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:49:58.05468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.487212ms","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-07-17T13:49:58.054751Z","caller":"traceutil/trace.go:171","msg":"trace[1756005512] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:4856; }","duration":"111.568553ms","start":"2026-07-17T13:49:57.943151Z","end":"2026-07-17T13:49:58.05472Z","steps":["trace[1756005512] 'agreement among raft nodes before linearized reading' (duration: 111.444151ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:50:53.787792Z","caller":"traceutil/trace.go:171","msg":"trace[2088349088] transaction","detail":"{read_only:false; response_revision:5360; number_of_response:1; }","duration":"113.917469ms","start":"2026-07-17T13:50:53.673851Z","end":"2026-07-17T13:50:53.787769Z","steps":["trace[2088349088] 'process raft request' (duration: 113.761937ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:51:10.956148Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3184} {"level":"info","ts":"2026-07-17T13:51:11.067732Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3184,"took":"110.245075ms","hash":1579745768,"current-db-size-bytes":41156608,"current-db-size":"41 MB","current-db-size-in-use-bytes":13398016,"current-db-size-in-use":"13 MB"} {"level":"info","ts":"2026-07-17T13:51:11.067777Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1579745768,"revision":3184,"compact-revision":-1} {"level":"info","ts":"2026-07-17T13:52:19.805238Z","caller":"traceutil/trace.go:171","msg":"trace[332225988] transaction","detail":"{read_only:false; response_revision:5945; number_of_response:1; }","duration":"189.799672ms","start":"2026-07-17T13:52:19.615417Z","end":"2026-07-17T13:52:19.805217Z","steps":["trace[332225988] 'process raft request' (duration: 189.63233ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:52:19.895437Z","caller":"traceutil/trace.go:171","msg":"trace[648585109] linearizableReadLoop","detail":"{readStateIndex:6224; appliedIndex:6219; }","duration":"151.525456ms","start":"2026-07-17T13:52:19.743891Z","end":"2026-07-17T13:52:19.895416Z","steps":["trace[648585109] 'read index received' (duration: 61.155821ms)","trace[648585109] 'applied index is now lower than readState.Index' (duration: 90.369035ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:52:19.895716Z","caller":"traceutil/trace.go:171","msg":"trace[2012462077] transaction","detail":"{read_only:false; response_revision:5948; number_of_response:1; }","duration":"278.446283ms","start":"2026-07-17T13:52:19.617242Z","end":"2026-07-17T13:52:19.895688Z","steps":["trace[2012462077] 'process raft request' (duration: 278.126058ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:52:19.895738Z","caller":"traceutil/trace.go:171","msg":"trace[366358460] transaction","detail":"{read_only:false; response_revision:5946; number_of_response:1; }","duration":"279.608759ms","start":"2026-07-17T13:52:19.616084Z","end":"2026-07-17T13:52:19.895693Z","steps":["trace[366358460] 'process raft request' (duration: 279.144401ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:52:19.895777Z","caller":"traceutil/trace.go:171","msg":"trace[786287830] transaction","detail":"{read_only:false; response_revision:5947; number_of_response:1; }","duration":"279.67738ms","start":"2026-07-17T13:52:19.616087Z","end":"2026-07-17T13:52:19.895764Z","steps":["trace[786287830] 'process raft request' (duration: 279.257614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:52:19.895865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.947772ms","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-07-17T13:52:19.895866Z","caller":"traceutil/trace.go:171","msg":"trace[746848996] transaction","detail":"{read_only:false; response_revision:5949; number_of_response:1; }","duration":"278.481113ms","start":"2026-07-17T13:52:19.617339Z","end":"2026-07-17T13:52:19.89582Z","steps":["trace[746848996] 'process raft request' (duration: 278.055868ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:52:19.895932Z","caller":"traceutil/trace.go:171","msg":"trace[2040431426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5949; }","duration":"152.053664ms","start":"2026-07-17T13:52:19.743861Z","end":"2026-07-17T13:52:19.895915Z","steps":["trace[2040431426] 'agreement among raft nodes before linearized reading' (duration: 151.850051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:52:19.897741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.365647ms","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-07-17T13:52:19.897873Z","caller":"traceutil/trace.go:171","msg":"trace[457146737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5950; }","duration":"152.461588ms","start":"2026-07-17T13:52:19.745326Z","end":"2026-07-17T13:52:19.897787Z","steps":["trace[457146737] 'agreement among raft nodes before linearized reading' (duration: 152.313716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:52:19.898203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.242921ms","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-07-17T13:52:19.898226Z","caller":"traceutil/trace.go:171","msg":"trace[1486817760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5950; }","duration":"103.302311ms","start":"2026-07-17T13:52:19.794918Z","end":"2026-07-17T13:52:19.89822Z","steps":["trace[1486817760] 'agreement among raft nodes before linearized reading' (duration: 103.17123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:52:19.898353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.507554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-17T13:52:19.898401Z","caller":"traceutil/trace.go:171","msg":"trace[1594646553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5950; }","duration":"103.586506ms","start":"2026-07-17T13:52:19.794799Z","end":"2026-07-17T13:52:19.898385Z","steps":["trace[1594646553] 'agreement among raft nodes before linearized reading' (duration: 103.493164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:52:19.898635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.681817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-17T13:52:19.898663Z","caller":"traceutil/trace.go:171","msg":"trace[1556023064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5950; }","duration":"103.738927ms","start":"2026-07-17T13:52:19.794918Z","end":"2026-07-17T13:52:19.898657Z","steps":["trace[1556023064] 'agreement among raft nodes before linearized reading' (duration: 103.652186ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:56:10.963149Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5464} {"level":"info","ts":"2026-07-17T13:56:11.01865Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5464,"took":"54.413746ms","hash":3269512160,"current-db-size-bytes":41156608,"current-db-size":"41 MB","current-db-size-in-use-bytes":20406272,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-07-17T13:56:11.018746Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3269512160,"revision":5464,"compact-revision":3184} {"level":"warn","ts":"2026-07-17T13:56:39.616546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.573704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3558"} {"level":"info","ts":"2026-07-17T13:56:39.616639Z","caller":"traceutil/trace.go:171","msg":"trace[502192044] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8594; }","duration":"120.718228ms","start":"2026-07-17T13:56:39.4959Z","end":"2026-07-17T13:56:39.616619Z","steps":["trace[502192044] 'range keys from in-memory index tree' (duration: 120.41546ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:56:40.334112Z","caller":"traceutil/trace.go:171","msg":"trace[1812581618] transaction","detail":"{read_only:false; response_revision:8600; number_of_response:1; }","duration":"106.826396ms","start":"2026-07-17T13:56:40.227267Z","end":"2026-07-17T13:56:40.334093Z","steps":["trace[1812581618] 'process raft request' (duration: 106.751004ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:56:40.334129Z","caller":"traceutil/trace.go:171","msg":"trace[2047957986] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"107.978056ms","start":"2026-07-17T13:56:40.226116Z","end":"2026-07-17T13:56:40.334094Z","steps":["trace[2047957986] 'process raft request' (duration: 107.802632ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:56:40.334479Z","caller":"traceutil/trace.go:171","msg":"trace[266145593] transaction","detail":"{read_only:false; response_revision:8601; number_of_response:1; }","duration":"106.751986ms","start":"2026-07-17T13:56:40.227682Z","end":"2026-07-17T13:56:40.334434Z","steps":["trace[266145593] 'process raft request' (duration: 106.366505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:56:40.334675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.762835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3c8d9cdf-6672-4673-b64b-19ac9fec3480\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-07-17T13:56:40.334723Z","caller":"traceutil/trace.go:171","msg":"trace[1397355917] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3c8d9cdf-6672-4673-b64b-19ac9fec3480; range_end:; response_count:1; response_revision:8602; }","duration":"106.843137ms","start":"2026-07-17T13:56:40.227866Z","end":"2026-07-17T13:56:40.334709Z","steps":["trace[1397355917] 'agreement among raft nodes before linearized reading' (duration: 106.698903ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:56:40.334493Z","caller":"traceutil/trace.go:171","msg":"trace[2027765994] linearizableReadLoop","detail":"{readStateIndex:8962; appliedIndex:8958; }","duration":"106.58616ms","start":"2026-07-17T13:56:40.227893Z","end":"2026-07-17T13:56:40.334479Z","steps":["trace[2027765994] 'read index received' (duration: 27.042992ms)","trace[2027765994] 'applied index is now lower than readState.Index' (duration: 79.542028ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:56:40.334592Z","caller":"traceutil/trace.go:171","msg":"trace[1887526058] transaction","detail":"{read_only:false; response_revision:8602; number_of_response:1; }","duration":"101.264061ms","start":"2026-07-17T13:56:40.23331Z","end":"2026-07-17T13:56:40.334574Z","steps":["trace[1887526058] 'process raft request' (duration: 100.996734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:56:43.380828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.092073ms","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-07-17T13:56:43.38092Z","caller":"traceutil/trace.go:171","msg":"trace[1902115802] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8637; }","duration":"117.238607ms","start":"2026-07-17T13:56:43.26366Z","end":"2026-07-17T13:56:43.380898Z","steps":["trace[1902115802] 'range keys from in-memory index tree' (duration: 116.956289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:56:50.630641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.519272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:56:50.630718Z","caller":"traceutil/trace.go:171","msg":"trace[951043966] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8747; }","duration":"102.662046ms","start":"2026-07-17T13:56:50.528039Z","end":"2026-07-17T13:56:50.630701Z","steps":["trace[951043966] 'count revisions from in-memory index tree' (duration: 102.42652ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:58:23.936323Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-17T13:58:23.942305Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-17T13:58:23.942373Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-17T14:00:11.710518Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002a33.wal"} {"level":"info","ts":"2026-07-17T14:00:29.799748Z","caller":"traceutil/trace.go:171","msg":"trace[1629251639] transaction","detail":"{read_only:false; response_revision:10582; number_of_response:1; }","duration":"111.603291ms","start":"2026-07-17T14:00:29.688128Z","end":"2026-07-17T14:00:29.799732Z","steps":["trace[1629251639] 'process raft request' (duration: 111.57864ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:00:29.800019Z","caller":"traceutil/trace.go:171","msg":"trace[1436016221] transaction","detail":"{read_only:false; response_revision:10580; number_of_response:1; }","duration":"113.425758ms","start":"2026-07-17T14:00:29.686588Z","end":"2026-07-17T14:00:29.800014Z","steps":["trace[1436016221] 'process raft request' (duration: 113.004639ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:00:29.800085Z","caller":"traceutil/trace.go:171","msg":"trace[186035140] transaction","detail":"{read_only:false; response_revision:10581; number_of_response:1; }","duration":"112.004859ms","start":"2026-07-17T14:00:29.688075Z","end":"2026-07-17T14:00:29.80008Z","steps":["trace[186035140] 'process raft request' (duration: 111.607591ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:01:10.969304Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7809} {"level":"info","ts":"2026-07-17T14:01:11.131028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7809,"took":"160.258472ms","hash":1493646860,"current-db-size-bytes":41156608,"current-db-size":"41 MB","current-db-size-in-use-bytes":35516416,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-17T14:01:11.131079Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1493646860,"revision":7809,"compact-revision":5464}